Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

jdk11 extended.openjdk: s390xLinux: sun/security/krb5/auto/rcache_usemd5.sh.rcache_usemd5 : Exception.. #12177

Closed
andrew-m-leonard opened this issue Mar 10, 2021 · 16 comments
Closed

jdk11 extended.openjdk: s390xLinux: sun/security/krb5/auto/rcache_usemd5.sh.rcache_usemd5 : Exception.. #12177

andrew-m-leonard opened this issue Mar 10, 2021 · 16 comments
Labels
test failure
Milestone
JTReg Backlog

Comments

@andrew-m-leonard
Copy link
Contributor

sun/security/krb5/auto/rcache_usemd5.sh.rcache_usemd5
Works with Hotspot, fails with OpenJ9:

17:01:10  openjdk version "11.0.11" 2021-04-20
17:01:10  OpenJDK Runtime Environment AdoptOpenJDK (build 11.0.11+5)
17:01:10  Eclipse OpenJ9 VM AdoptOpenJDK (build openj9-0.26.0-m1, JRE 11 Linux s390x-64-Bit Compressed References 20210309_909 (JIT enabled, AOT enabled)
17:01:10  OpenJ9   - b227feba2
17:01:10  OMR      - 4665e2f72
17:01:10  JCL      - 73ab98c885 based on jdk-11.0.11+5)

https://ci.adoptopenjdk.net/job/Test_openjdk11_j9_extended.openjdk_s390x_linux/14/consoleFull

18:37:37  Start KDC on 10238
18:37:37  PROC: Nsanity cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Nsanity]
18:37:37  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Nsanity 
18:37:37  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi.so
18:37:37  PROC: Nsanity readline: [GSSLibStub:110] libgssapi.so: cannot open shared object file: No such file or directory
18:37:37  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi_krb5.so
18:37:37  PROC: Nsanity readline: [GSSLibStub:110] libgssapi_krb5.so: cannot open shared object file: No such file or directory
18:37:37  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi_krb5.so.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Loaded GSS library: libgssapi_krb5.so.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.2.840.113554.1.2.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.3.5.1.5.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.2.840.48018.1.2.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.3.6.1.5.2.5
18:37:37  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.3.6.1.5.5.2
18:37:37  PROC: Nsanity readline: SunNativeGSS: Created GSSLibStub for mech 1.2.840.113554.1.2.2
18:37:37  PROC: Nsanity readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nsanity readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nsanity readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4396706979760
18:37:37  PROC: Nsanity readline: <EOF>
18:37:37  PROC: C cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, ReplayCacheTestProc, initiator]
18:37:37  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts ReplayCacheTestProc initiator 
18:37:37  PROC: Ja cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Dsun.security.krb5.rcache=dfl, -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1, -Djdk.net.hosts.file=TestHosts, -Djdk.krb5.rcache.useMD5=true, ReplayCacheTestProc, Ja]
18:37:37  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Dsun.security.krb5.rcache=dfl -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1 -Djdk.net.hosts.file=TestHosts -Djdk.krb5.rcache.useMD5=true ReplayCacheTestProc Ja 
18:37:37  PROC: Jb cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Dsun.security.krb5.rcache=dfl, -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1, -Djdk.net.hosts.file=TestHosts, -Djdk.krb5.rcache.useMD5=true, ReplayCacheTestProc, Jb]
18:37:37  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Dsun.security.krb5.rcache=dfl -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1 -Djdk.net.hosts.file=TestHosts -Djdk.krb5.rcache.useMD5=true ReplayCacheTestProc Jb 
18:37:37  PROC: Na cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Na]
18:37:37  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Na 
18:37:37  PROC: Nb cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:-UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Nb]
18:37:37  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/scratch/1 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:-UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/sun/security/krb5/auto/rcache_usemd5.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16153093836725/jdk_security4_1/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_s390x_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Nb 
18:37:37  PROC: C println: client1 host2/localhost
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client1@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37  KrbException: Additional pre-authentication required (25)
18:37:37  	at KDC.processAsReq(KDC.java:1294)
18:37:37  	at KDC.processMessage(KDC.java:772)
18:37:37  	at KDC$1.run(KDC.java:1524)
18:37:37       Error 25 Additional pre-authentication required
18:37:37  >>>>> UDP request honored
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client1@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37       Return client1@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: client1 call initSecContext
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> null sends TGS-REQ for host2/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
18:37:37  RABBIT.HOLE> verifying referral for host2/localhost
18:37:37  RABBIT.HOLE> presenting a ticket of client1@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
18:37:37       Return client1@RABBIT.HOLE ticket for host2/localhost@RABBIT.HOLE, flags INITIAL
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: PROCISFUN:YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs6A94P+KZ80xafPYmVBUaAFsAkC5m0frSIm4PnB0+nOVX7Eh/z++vQ5AUGMNNtqWvS9ax70SIRSU3hDSbH+iNL7SfzO4focqLY4flB7gIscUVC+o3HwLn+TczoZeS7suW1aRIBju7hzomsAp3ilIAW8GOgIb2uAXmy2gys75LhUAGLvLOqTfA7aUPwjJpFpLk1hyHmimiEbMgNyUOsc9XWY+4QLuRN1rYRIEg0TcKWiU+vZdpIHAMIG9oAMCARGigbUEgbJqtf1IPD4Wwsf8NltjVmYkV5jWUoBkKahAAqNdIy3Ysb1WkhJM/SQICr0YKaznXB1lbPwGoeatoHikQIXqWNwNQNC2Jj/Yp2aBbjdzJ0sbbgxa3ANMa8D/+mcEkO6dU0Xfgbrwr2sK2e5bZQKlGZuokM1cvK36twY1oHAFvZdW6Euftbaza7OkSacA1iNgP4hWebvmiskWFT5fqAkUaXcOc//yvX9asXmco/tR0DQbuXZK
18:37:37  PROC: C println: client2 host2/localhost
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client2@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37  KrbException: Additional pre-authentication required (25)
18:37:37  	at KDC.processAsReq(KDC.java:1294)
18:37:37  	at KDC.processMessage(KDC.java:772)
18:37:37  	at KDC$1.run(KDC.java:1524)
18:37:37       Error 25 Additional pre-authentication required
18:37:37  >>>>> UDP request honored
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client2@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37       Return client2@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: client2 call initSecContext
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> null sends TGS-REQ for host2/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
18:37:37  RABBIT.HOLE> verifying referral for host2/localhost
18:37:37  RABBIT.HOLE> presenting a ticket of client2@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
18:37:37       Return client2@RABBIT.HOLE ticket for host2/localhost@RABBIT.HOLE, flags INITIAL
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: PROCISFUN:YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1VyeudqGh6SIu7Gf9ReiRj4d1/EjRBoraQajNPdjf93Pxx+hkH2sx+w+HtuqL+YwKLJqCYHhIwG/jAsawJLSzux7BxxIoOatOC2ztlpDFbWqC5YHsg/0DLtAlsf/4D2DsqGWZLNgUiduYcBOAXK2lb8IY5eSKszJuMnRy4CadHNs4jitkQv7QQ0ozWu38n6M/IfTN6r3fh4RPN8k3ZpIh+uVXl5AOLhqozUPqfOHSjKicGxpIHAMIG9oAMCARGigbUEgbI28ikLsbuhuSmjYueIAnLoqODU0ZvO4/OhBPc/UPoDrrSdOH3qVOcoZHn8G1KNnFq4DcqD5SMVRRd5uhYpJsj0oweYcQ1LzNjo1Y5AFFkh4wbyYGl/t5xEKM02YsrMUK1Or5JyZQsCus9X8U/7TRYpdL2l/yk8EtDDsSBC1W6grTozc7ppIAf/Esf8OMAdhkyuvTMTVbJOgHfuNJKz9EHABZcJusWG1LWPceKdp8Csn9V8
18:37:37  PROC: C println: client1 host0/localhost
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client1@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37  KrbException: Additional pre-authentication required (25)
18:37:37  	at KDC.processAsReq(KDC.java:1294)
18:37:37  	at KDC.processMessage(KDC.java:772)
18:37:37  	at KDC$1.run(KDC.java:1524)
18:37:37       Error 25 Additional pre-authentication required
18:37:37  >>>>> UDP request honored
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client1@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37       Return client1@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: client1 call initSecContext
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> null sends TGS-REQ for host0/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
18:37:37  RABBIT.HOLE> verifying referral for host0/localhost
18:37:37  RABBIT.HOLE> presenting a ticket of client1@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
18:37:37       Return client1@RABBIT.HOLE ticket for host0/localhost@RABBIT.HOLE, flags INITIAL
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: PROCISFUN:YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1PYaLQ7pblgu+xZ+r39VMrvSqQP4636enj82GNWCDYs/WsL1nVacSWtXpNt5VzIvaORAG/TGwsK2Ikk8FrgsnIiXSKUGWbNdTtTt04vGnKMaErjQNpFVNdmNEMGf6FDExW7Mo6KLCXLP8udQYo25L4j1IsC1XkLscgKnbNtBXNVg0iaTyvQCLnRaNpoJDgXYiJqMnmYgw/cL4qTeJNob2pRENkd6vRl3EytyJfxLS5+d/mdpIHAMIG9oAMCARGigbUEgbK71UggPdYn9otdmBuKaN8g//h55pcgaJfZydU55zZHlwEDkDi2u6yorwIvV+d5Un1xjztMFoyroErmLE29QzyvfE44n17uj0Do1uNQg8f/MUdCOTrP2j9k6pF/kwKjwMCvUSjEx/rF1NLA6Y+L3Z0yPmvUvru26EgLmR3fZJGDdAwjux487XkNGlquxX3QCvR09Nk1zSw9uw6LQODWtEMz1boFO6yM3b9dBa2iZBs91ZKS
18:37:37  PROC: C println: client4 host3/localhost
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client4@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37  KrbException: Additional pre-authentication required (25)
18:37:37  	at KDC.processAsReq(KDC.java:1294)
18:37:37  	at KDC.processMessage(KDC.java:772)
18:37:37  	at KDC$1.run(KDC.java:1524)
18:37:37       Error 25 Additional pre-authentication required
18:37:37  >>>>> UDP request honored
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE> client4@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
18:37:37       Return client4@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
18:37:37  >>>>> UDP request honored
18:37:37  PROC: C readline: client4 call initSecContext
18:37:37  -----------------------------------------------
18:37:37  >>>>> UDP packet received
18:37:37  RABBIT.HOLE
18:37:37  
18:37:37  ...
18:37:37  Output overflow:
18:37:37  JT Harness has limited the test output to the text
18:37:37  at the beginning and the end, so that you can see how the
18:37:37  test began, and how it completed.
18:37:37  
18:37:37  If you need to see more of the output from the test,
18:37:37  set the system property javatest.maxOutputSize to a higher
18:37:37  value. The current value is 100000
18:37:37  ...
18:37:37  
18:37:37  
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs3nFkXO43hrtRDuI61db6xChq7SRmoSaqkTk+d/+wgtfe1Lp10ZaIRuUHvcWLBjKawgzxWa6fobwqvqP/fvzcAvA57r0b1muLtJECbG/923G7YplfsoBsuoXxh4HwGdfBSGsFo1ks3AejMa9gBHtb7REqvX+VF0gJ9+JwR/LT23puKkS62+i2YeI4lgl5fhDmwDteQaLj/VY6f+eCg7Jmt5R57OpwNCZ8c141Hr4i7VPPDd5pIHAMIG9oAMCARGigbUEgbKx+wESXOa6NERUpfvDCGengHhjMxkbZ905d9lMP1MGxFGeJcJGQeyi0oIceG3QG6Rxh0Yq2EOpV+5sTW7ChY8bkK/VSYQBJay5U97pFhiyc11HnCZZASeZCwO2xIzgF1KORxamIH+8m7n3XGb19xqqf8cvxgjE0yFPxJep4OM5bB+HkjJAUWAHUvHiCY91hQelJu773/RZGHPRhdwh+La9oPZRhesYXqoJT2uhlRDOw1FW
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395833379824
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395833379824, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395833379824, pContext=0, pDelegCred=0
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Na readline: PROCISFUN:false
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs5nrgj7sF0pWD/5lmIXsXjgcF06LS3BxDH/yv3utx02tfs1C3/zgc7nEqWMKK1FqCZrZxTYKvC+XdAiHN3lRfQRxNUmDkEHAe96+hbO9mMYxKddJxC6NqaDwmDM6a08Omr7EUzc40F6VmiZj3k5pUeyIByJIoHXOtVV81HwxyBDRiomgbb1hv4V2TZ5EHZtiIdUChcCgU+Y7QUY1JG8Mvu6r5j5FjcXysKznZUcspxO89mslpIHAMIG9oAMCARGigbUEgbLnQCoUx2DQDhkZiGL6Aory+TiHsGSsswq/zzwKhtqxtGK4RAoOP6/JGE/t9atKcacD0542Zdxi3bRPde1UKERhAuJ93pgJxZ5KmiZmOYZ2Jm/LGtbo2N8eTug/VDEPO3pbFeU+R2VLeWib3x4HIiY1Mvl59h+s/HHjHgZqsWEJTnCEYSklR+Pjczccg4T9Hg/7fo3Eg3IK9wwWcs/crIFw9nCU926gNW6J3jEzhCrWj6OR
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834674976
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834674976, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834674976, pContext=4395834353872, pDelegCred=0
18:37:37  PROC: Na readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834353872
18:37:37  PROC: Na readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834750272
18:37:37  PROC: Na readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834750272
18:37:37  PROC: Na readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834353872, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834764304
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834764304
18:37:37  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834674976
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834764304, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834674976
18:37:37  PROC: Na readline: PROCISFUN:true
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs+b/aE9v85xMgC0v2xf1VeIKq7c6N9Bd1upW4joZyGRzt5FD1upICzT0nE5YG78CV8AmQSgDA8c9ViZCOdt8heU5l9FzV5ba4mM69CNChJpppT6jS4nXBffhP3IJpdL/qoRW6L6eS+7earOpvGAwpb44xWI+ued8knG4BeEbpCwYV1lHjdCVMSeT6QqKmB/U8mTZRsqdwh/CbPG5AGWno5GZxPA2mJa+rWIQKnul5wnuQspXpIHAMIG9oAMCARGigbUEgbJN8O+fPTpFTg/9MKHD7150xJyF6Dw0vRQ7IUKBgLwyJ6cg4row8S6EaCtsKdXqQ58BHv5du8x6weRgXhu+0C/RQG6ndBCiPwrulOSwR14pQ0cnN1yxLnyaI/agA4+yyVo+TZlBcWzuTkEvEt8J43oecP1ASUsDSLPFCLJ6Fdzy8bORfwwHavstck/fPo83Eacl2+lXmg1rvasFH+gR9CtpKvTC5SQSWe5p1WCNl56Oukk8
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834845984
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834845984, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834845984, pContext=4395834735120, pDelegCred=0
18:37:37  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834735120
18:37:37  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395835014624
18:37:37  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395835014624
18:37:37  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834735120, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834736208
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834736208
18:37:37  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834845984
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834736208, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834845984
18:37:37  PROC: Nb readline: PROCISFUN:true
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1VyeudqGh6SIu7Gf9ReiRj4d1/EjRBoraQajNPdjf93Pxx+hkH2sx+w+HtuqL+YwKLJqCYHhIwG/jAsawJLSzux7BxxIoOatOC2ztlpDFbWqC5YHsg/0DLtAlsf/4D2DsqGWZLNgUiduYcBOAXK2lb8IY5eSKszJuMnRy4CadHNs4jitkQv7QQ0ozWu38n6M/IfTN6r3fh4RPN8k3ZpIh+uVXl5AOLhqozUPqfOHSjKicGxpIHAMIG9oAMCARGigbUEgbI28ikLsbuhuSmjYueIAnLoqODU0ZvO4/OhBPc/UPoDrrSdOH3qVOcoZHn8G1KNnFq4DcqD5SMVRRd5uhYpJsj0oweYcQ1LzNjo1Y5AFFkh4wbyYGl/t5xEKM02YsrMUK1Or5JyZQsCus9X8U/7TRYpdL2l/yk8EtDDsSBC1W6grTozc7ppIAf/Esf8OMAdhkyuvTMTVbJOgHfuNJKz9EHABZcJusWG1LWPceKdp8Csn9V8
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834735184
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834735184, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834735184, pContext=0, pDelegCred=0
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Nb readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Nb readline: PROCISFUN:false
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs8bI76FijKKlxMCQPpGjmbIHjOOGEWRtinbMIpgf8cLOuplAiyLPLwoHUobp1HodUwytfBv4+vhEhm+dADVxjIsPg0efENK5sdjRC2oIYTxGNEMbZDKLsJy4lHDgFjTd1ygzASuj4vJowMEJqT92VqCzRW/Kq2MFTNLBRGCmywsX+KE7dMsGqBjF99rm9zd1UJ3LxQ4UycJCzj0ejmv+IL4I//qh6w8tpQiAVNlGTr72rnKApIHAMIG9oAMCARGigbUEgbJ01EPJDRPDnsEsc12hKVghuWkGH6ZU+4UKVFhOSje7/5c7ZpF6FX6QHXHo95gYXgQVWrWA+uMJ600cn9R5JWrTHoKfooEGxAAsLCu7xT+WBO1vRGS0RskOnPGaOFpuGrYiSNlFrMjdbScP8yoO6WUGNZZArULbvFvAHfaxQmWGLwTZ6By1KhNsXFZzO/q5BxAZiZfXab5C+8k5Pb03iah0HgxvI++r6G0e2WDvjM/ErsyE
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:false
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1PYaLQ7pblgu+xZ+r39VMrvSqQP4636enj82GNWCDYs/WsL1nVacSWtXpNt5VzIvaORAG/TGwsK2Ikk8FrgsnIiXSKUGWbNdTtTt04vGnKMaErjQNpFVNdmNEMGf6FDExW7Mo6KLCXLP8udQYo25L4j1IsC1XkLscgKnbNtBXNVg0iaTyvQCLnRaNpoJDgXYiJqMnmYgw/cL4qTeJNob2pRENkd6vRl3EytyJfxLS5+d/mdpIHAMIG9oAMCARGigbUEgbK71UggPdYn9otdmBuKaN8g//h55pcgaJfZydU55zZHlwEDkDi2u6yorwIvV+d5Un1xjztMFoyroErmLE29QzyvfE44n17uj0Do1uNQg8f/MUdCOTrP2j9k6pF/kwKjwMCvUSjEx/rF1NLA6Y+L3Z0yPmvUvru26EgLmR3fZJGDdAwjux487XkNGlquxX3QCvR09Nk1zSw9uw6LQODWtEMz1boFO6yM3b9dBa2iZBs91ZKS
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:false
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs7yHiLw3tpuG/KszkTZx1DD3AvIC7NH96An+vpKxtnYODv/LnraS5saQDrsoKIC9xSVu2GA+5A9kwIscW8H4lU4Yk2lS3ZEyWigAr2ZFSBF4NvjV3QZgD9BsMKK/iGXN4hF7XY3Vau+MYlBwABBSWReiseD4ygHOpZSBXLpxI4rPAQXvFt26rRfR2IY43TEH+ytZ8iie1yzoSWC1rLa7sUR+vXoJqeOUcJGt45H2Udl8ySRppIHAMIG9oAMCARGigbUEgbLHyIF7VHMR/gQROgIk0qFC8fNkW96ASzSxmLwr55Juz0Dq/k8V1B9+LhTPBvcqlPXA3xPoCmWSDd3cv4S89BkfKwBvW9fBjDiXbSQ10gaUD6T/BBtKq4NnC1GndRHGbslVW+P99yT457TxyezqLhq+17StIgVhfiLrLN8jyB2mjtW5QT6hy8F4rj84VjBTBHzlQL51g514DE6DmbU8z+GMGQPr1JqoNmEtdenfKeSvOaM0
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:true
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs3nFkXO43hrtRDuI61db6xChq7SRmoSaqkTk+d/+wgtfe1Lp10ZaIRuUHvcWLBjKawgzxWa6fobwqvqP/fvzcAvA57r0b1muLtJECbG/923G7YplfsoBsuoXxh4HwGdfBSGsFo1ks3AejMa9gBHtb7REqvX+VF0gJ9+JwR/LT23puKkS62+i2YeI4lgl5fhDmwDteQaLj/VY6f+eCg7Jmt5R57OpwNCZ8c141Hr4i7VPPDd5pIHAMIG9oAMCARGigbUEgbKx+wESXOa6NERUpfvDCGengHhjMxkbZ905d9lMP1MGxFGeJcJGQeyi0oIceG3QG6Rxh0Yq2EOpV+5sTW7ChY8bkK/VSYQBJay5U97pFhiyc11HnCZZASeZCwO2xIzgF1KORxamIH+8m7n3XGb19xqqf8cvxgjE0yFPxJep4OM5bB+HkjJAUWAHUvHiCY91hQelJu773/RZGHPRhdwh+La9oPZRhesYXqoJT2uhlRDOw1FW
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834751808
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834751808, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834751808, pContext=0, pDelegCred=0
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Na readline: PROCISFUN:false
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QzGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs7RnEkTVuSpLUsL+ESNbHFYgecQDdugitDxhh9NiSOtNhSgbKQ7DWJgy/W3KyMRbXhMiQcXsQ6AhLcp6pMv4SeUpeQynBEWhu5Maxnj3X4Dzoz6moYWDmJH181UOP03fcd8+FYPgBdcIlBAIx5LeV21AqRmMEgncoXXBOq9Iu/nICgM11l6OZZ71426QZ8uq4jN73N1SRk/krXyPVF0qMrllnpUKWFn1QS+wX4FGnOTik5eEpIHAMIG9oAMCARGigbUEgbI75eYH6VGxu15W1rQUOUQD3etK+RWRDDQF4b9lRdSYXB5a1LhsxuoqcicDCFqe5MXex3UYoux47gSaA2Wr+gbKes3wKBj10LQDvX2s8l5UPMCU5QwCo+TZDbmw4/9dC/BXGv0pRJh5UVKnTqR3AndrqZ+ccG+CrdO0FCRzbwsl3UAKp2z6i8za5WrbSaHdIPN1Sj5TVXjcLCfnUexkgxw+YwLjKqLT57226wu/ALSzKR52
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:false
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs/oFxiReCCjNcA5nASUgIC0+azO16SX+V+m6WCqaSjuevYAmVxdMZw9X2le5ovqgBdwpPRlc5EzCoh+5OwtLlgPkHQiq13MdU3pToSrvuhTayp+AgapBx7dlujmOwsgXrryIASkERL/GSatY2RDEUKRPPKYooqXzh7I2PwXVu2DUmi3f4T7We2olxEgl5Bq0Hmy4TBowRp+1834bfjUsG8gg6oldOCfVP94EDcz78TtvDJeSpIHAMIG9oAMCARGigbUEgbLO2j6My9vR+6SI62Iz6gcDrgZmFEqz9J0BM9XAdkmvtoDONPHrMNrxW6KBoXr2woTez12BQnJFwPLTRncu+o97ktzVODuYj3bU6GI4OI+HkIVW9sMjcptJ9b4r9yMSUteuEkDYS62HuU+iNU9TrXD3LfwBpB7k8zTY5A0c5MCYukAGCTB7iFm7xHkczk7X99+G0GsJANffHWwcVhyFcQ3IQAFEFEr9BrQAW6QOUDAd+9iW
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:true
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs8kgnqMlycJis5fTzzHFs+esrAAkPWjOzsh8PDGR9au+qFrH78ko2y9hIW5y5jrF/TbEzCNPGj1Iawy3BKuzVr6N4msgh+qiJVWrtlYWj0J4/JmWew+JY4PWaleRjYgsED6IzIsM9k92P25dCZRA4P4IXLtfFFOBBDLL6LUz6sUnCEXwpF6a6OOdZvY7nNfhTzzxhGFmfaTMJe3Z8DoJDiCkGyLmZYWeZ8vY/+ew8pP4J2AtpIHAMIG9oAMCARGigbUEgbLytiuiQivRWeEYEh6Z++R2UCzeTziPT1KrtK/0WxZ5AzBtpJIcohMhmOilPcNZ0EChVkqT7/EOeI00IhswT/j55KvSzXyhdWCeT/vdq+keVoCg9oQEVt55mG0PFprAMQ4PtQDpEjtvKjPENVOfk688Kw+2339F4MTVULm+HjkpjF4SrDZOu74DauRaRvE4VUfTVvBTbh7dUnpPftK3WfChrvmtUdA/hJxEt3oIasg/RE5U
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834750896
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834750896, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834750896, pContext=4395834762352, pDelegCred=0
18:37:37  PROC: Na readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834762352
18:37:37  PROC: Na readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834862352
18:37:37  PROC: Na readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834862352
18:37:37  PROC: Na readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834762352, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834885056
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834885056
18:37:37  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834750896
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834885056, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834750896
18:37:37  PROC: Na readline: PROCISFUN:true
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6gYJKoZIhvcSAQICAQBuggHZMIIB1aADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs3RlteceaNtnjH2nWACx7QcRhoa4+w813n3uwYihX/rDBet01rMVTxy6NvucddAYwo3n7UTF+KDLofe3tR7YdN7Xs3z7yo+n8uOgqPZwR8melCliEwUyR8Qpg8beq+HtAN5Ubxok5e1QYCZNvSOEuW5/2jHYXwZmEhaVJF9axYkTwmdNR/Vyyyk9w1z8E+8C9w3J6Sff4fZuFVTmreF/XE8kdX4U3Lv1+yfQnA7NWXAOKYJipIG/MIG8oAMCARGigbQEgbHyOhx0UsCqSZmjPoJ0d+EiJHFb4y/8pInXgCwBO4hnr4ciorxpFc0XI84sS7SlXi0Shod5jfgnJbjMQiIqq5v08ZdzUvCm6vGz+kGDVqT6HBr6RI9Lhv76xqqctpIUkFJLvUu23mWPDm5euMmU6kiFaG2xqBVplXXegdhBMYB1T2nn/LrRd7jNuJQtlY7rMgORiYXXoE84QVF79PtcORT4tdPnamQKNtmAditwBwfPv5Y=
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834861088
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 479
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 494
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=494
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834861088, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834861088, pContext=4395834765904, pDelegCred=0
18:37:37  PROC: Na readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834765904
18:37:37  PROC: Na readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834907616
18:37:37  PROC: Na readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834907616
18:37:37  PROC: Na readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=137
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834765904, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834920928
18:37:37  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834920928
18:37:37  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834861088
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834920928, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834861088
18:37:37  PROC: Na readline: PROCISFUN:true
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs6eVDYydonZnFxgb7tqUcsDrDTNMum6szuEFjw+UKc8Gja0DQU7CjM9N2eOpGfDg6zeLl/SiPjlhADWmD/ldcUneDObxT9jxOxGM+LUwLHT9eVETrdQ9RTIrEwYvCYIgCBqHXG+7Yv1IA8W+wcpxBrPjdj8CyWKIL2EDK4J7DYYvf8FTGBAlU+hrzI1Bp2cAwFDGt+/4usATYxMZ/aOrdh/3nRScu7njDQV6j/5zKGKZuHoDpIHAMIG9oAMCARGigbUEgbIoNfWWAKBO3yZBSWIP6OFUzx0xTUVZf/OtLdWaaOp3YERDkB3dlaxG8d9U2RWEeuwNWea6Jtr5hgLmlZJZovnCcokN9CmHJJ2gWDHDeJIjLgVRvfRqa96lCJu5/MXJNjf9ryn3PIrXIHyyWec3c/TMz5yBTxwPY1gyevd+CmlbY2QnmcayrUr66+vJ8VFKYCIQYoEu42dg7lSLFX9OiW7DKRboganyDEGX1N08LMUvnYkL
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:true
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs6eVDYydonZnFxgb7tqUcsDrDTNMum6szuEFjw+UKc8Gja0DQU7CjM9N2eOpGfDg6zeLl/SiPjlhADWmD/ldcUneDObxT9jxOxGM+LUwLHT9eVETrdQ9RTIrEwYvCYIgCBqHXG+7Yv1IA8W+wcpxBrPjdj8CyWKIL2EDK4J7DYYvf8FTGBAlU+hrzI1Bp2cAwFDGt+/4usATYxMZ/aOrdh/3nRScu7njDQV6j/5zKGKZuHoDpIHAMIG9oAMCARGigbUEgbIoNfWWAKBO3yZBSWIP6OFUzx0xTUVZf/OtLdWaaOp3YERDkB3dlaxG8d9U2RWEeuwNWea6Jtr5hgLmlZJZovnCcokN9CmHJJ2gWDHDeJIjLgVRvfRqa96lCJu5/MXJNjf9ryn3PIrXIHyyWec3c/TMz5yBTxwPY1gyevd+CmlbY2QnmcayrUr66+vJ8VFKYCIQYoEu42dg7lSLFX9OiW7DKRboganyDEGX1N08LMUvnYkL
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834905200
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834905200, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834905200, pContext=0, pDelegCred=0
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Na readline: PROCISFUN:false
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBsyp3VrjTn8BCmfLJQ2x8Wm+WExitqRYuuWNHBDcNdfpC28t5xz2a1/JuIl7LwtVVBUSJE5WFl2JJKXrFR0+NExqWYjjpcDMccVwCZA2Vsc+9PkDiWDJmK4k7hte7PdUM9uWI3RcOLCkxGpGakEWq7Z2g02ie9tOoKfRQjLOaQCFA9dpNnlswdEnBV1m+zU0Ieg9s2Z4hldGZaYflcNrDzbWN0diDsSYScBQc2UFfOm33mj2bpIHAMIG9oAMCARGigbUEgbL/M43tn0he+J/9FMl4NdgW0ugUy7Sx22gNagRywzIOKwRJTt2JL8Fn3BRby2dy5YUs/9p7ZFTXssLaETMozxIMpTOJM0uZoWKmHOn3kJjAK7U075MSAtJdgje+g3Yb1CGjTnXCuxAG3PtokYl7tbKkKLnAaopvxqeDSL/Gk10zIsVyio7lU7XGS2DwrZbV52DjJVvWgipBXZP2N7icd8lIrZdWRvWlP1TjX9kmEgZpjrjI
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834739760
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834739760, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834739760, pContext=4395834736304, pDelegCred=0
18:37:37  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834736304
18:37:37  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834769376
18:37:37  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834769376
18:37:37  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834736304, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834781856
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834781856
18:37:37  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834739760
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834781856, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834739760
18:37:37  PROC: Nb readline: PROCISFUN:true
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs3AtRwGd/U7dpOpsq9sDft3KQj0t+UXAnyCeX7XqpRPcYqekifbATJ8RZM+q95/2ia6lObvm4E1I5o71cwqwcUaOFDMwBkwvwW1WuGOACxR9BBYaHmrFwyQ39grAtYJPJtKfQLC7MpZ4EX/jzVH/zJkqm3pD8NEpFKwAdzfbFVWxLdiHFd19uDTJz3TQopg47WsgbX/e5xuLGpxWAh9Zcb6S1FYi/GR2d1dbOgUSJK8HXEkTpIHAMIG9oAMCARGigbUEgbKGRZf6g55lXBaEqFe0Ec+9BaLHmpRyx4LUhRGcG3B6+hgHmUKRCHM0fsiaIfZHPGOi5srS6a8oq/uruUM6PZyYdW3WRvprkP/WLRkuvZ6oLs8l32gcj0WeAo/ZNu2F0DemslAPORO+0QqkFb9BVybj0tMSA4xrY4UXgWhplLXMKmGapTAX/7U01rCfOc53dK5KrbkghcE2P/qtsGL4UukT6yZQdmlQJxhBioTXjpeWNb9d
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:true
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs0IpxjQKKbJIyY3CbO+S/KsoXRph6GOt+mQMbk3JauPacUCKMA7tKwGmM5mV6/JcVQz02dQtiiqgR1SeVv/0gQNvfKj1hB3AJhBVHQwhWvtnHU4rudE7dcQ253X7NKQdY04Bg0o7yLsy1J3JTM0sHh+SD+pNhyt5grKgixsjSs3AdllEHYD+RCV4hJjzRg2Ftjxsk6Nho/Fs15gKTGYh86fraqCYsjm+Z0gCSPVDtqEUSS2HpIHAMIG9oAMCARGigbUEgbJpH2a6RJFPPeTZbIAddC7uNrGsw25XtsEgi9tRXxlkAvL5RyXcNGycwghCAHBS/U2m7GEHJurGpeiSTHeNrOEcrgSN3tHB3PHN25fZyIlmHOSjp5vW6W/t5hJu9P8lPZzFYBNH5J6BgnnCiZtz8/twY8hncPG7qfgHoFGblcBiIypoAuDHQQMCKeaSaKxtc+2KDSdIZ0iuaT09Thu7VKcCg2kaesSsj7nidJSnUjrZ6D9J
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:true
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QzGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs8dqkXpv2W03L8SR9GtPIcmlsGvEZblv49DSHKdjgyxQlwMB2PkfWauC8UARA/tTQDzAUmyhBvttDt/zwIQyIx6neDJtNiuOdM7z6rH76h7uumrzMtHBjIvk7S2bwYcnqcok76V4j5kE02LtOLmPd0aFTTARXn1V3SVr2umwSJQDy3AmaoM7MzKzrvdDm1RtInqnhpsirl4ghQcMIIdhgTeMVYh2ZC8Nw5utdSGXtOuNRC/upIHAMIG9oAMCARGigbUEgbKR98ID+iO+MKH+L1V2DjUYgg0yz/XF+kD4n0ajgCXevqNvfHClR9D9TmCzj/1JL55zYIMpy7EGsP+Co8GEkcPQGG6a8uZZYClHUXMIY21mRUCablgjqAgxSoPNPqqVtkULgly4jYtulkQkexpKiJkk1vFoA61KXL9AmvoJJeznWI+bsup/q0oYDx5WTqfkSYucUIYe5CUOPLuApUrvg4okZWLHuXWcqxcY3UNVYor6uzVe
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834768016
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834768016, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834768016, pContext=4395834737056, pDelegCred=0
18:37:37  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834737056
18:37:37  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834768256
18:37:37  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834768256
18:37:37  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834737056, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834800704
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834800704
18:37:37  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834768016
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834800704, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834768016
18:37:37  PROC: Nb readline: PROCISFUN:true
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6gYJKoZIhvcSAQICAQBuggHZMIIB1aADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1dWym8jL24mh8ueFjqtBag+sUJgaGKKlfd7xS/zdOfjDQTJNA2obBfKflF2jUEit9MGp97AQIL6IGvXNEhDbO9a++gKQiXEXUNnq5V4xLu6DBzYx9YlWCGE80XRVHMS4PkM6EOQDphxg8Bz8aZF9poKU+dmQvF8JpTRBPTIBPDyg+VxiB1TBCdyp47JFoM7mAD1CRa27iwKmcG2tS3A40PDJUc6vTXZJAwioh/DJTk/vmc0pIG/MIG8oAMCARGigbQEgbEwJ3DnpicpWaP6vL6CKzaLPeGUtRoPJ4pTjeXa51L2Z2ZUeCrsmFzPiQ43YuUpYCtsfG4wJm6tB81HZM90FAVY0QTT+0AbUjavhRTwwFGKxhiZUT+z7rjSZEoaHjURqgcW+foLyhHddFri3HjjOSN2zxvdrtJokOQGVU5ngsqabY8PiyQseJBGCk1ohQCE/YrG7qEhc3Q/rOe4btJaho0ceGUFtTZsA5/JppvbS0ImstU=
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:false
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6gYJKoZIhvcSAQICAQBuggHZMIIB1aADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs9OsgTP0D+MP2jv03qBxH6gDEyrvx+I/YoGBqde+HrposDgdij0lWguSrpjrNYlyRk1bRRILs1JJ+hR8ecse9f6tgUrkOXzq5w0RGLQIPNN54sFLSUpyt6aFnfdTpOXjtiFPrV9LinwXG8aulEd+PK4Xdo5XRXwa3BJbjYZEyaA1WpEg/vqAfqvI5iY/6QgicptahO+BAFaSFYpkdz+bq6xUwh08jqrBD7PYRgYyn2dlAwBLpIG/MIG8oAMCARGigbQEgbE1jtsNPievD/6fpG/2/UTisLYr2KqibOhyYebBXaJG87YkV4fc6EpGDLjaJMlb5jgJ/TU6RrKBeFWLP4lCSi1wiAXAHAFYosBDeP6MvsxrSuXgVOhWSgU00BADg0/8zLJhHgaA2p/DifSgNdZm8W4CraWFPNCRORauQq+Skykc3T9rnznkkpXwjhLdcp15BSu0eZGf+jlDn4p4ya8MN3rEv5D5NbPX6rozU41b1hi+Psk=
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:false
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QyGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs3zJ/zfeEpYZh4DpNgCjDhyKpIJonNl8UZxpiWbo178cIghdFBP1PtIOgH1mLnqvxBk985oU2OcM+Ae/U48xrQBRABe04tnp1BzXE/eI5nhtMR5I4vIp3sgWECuGd0fdFHm5n5S7+EEdKL0p6g50v+D3uycO9+AlJr2PuRQX+Vd6NzRUuR+3WGTXQUeAoBiEHYEzV+7cXjPbGm+boss/5YnjXG0h9WD/s4Jq4HXO7HuUdKwnpIHAMIG9oAMCARGigbUEgbJOCT2Bjr4yrc4Mlm75A5PfVng+pV2uakpwtZniqvGrXm8MQZcUQiSR7yQkVgRcwymis67qbsIj7vql/MnW67zHF7YLENBzGRnaxrweM8MsuwQi3OGzCfEpqGY5ol2k38IwPsb28nl07WD3UzKDDPkLaFdo0lOPRlOtLaT0+qLVyWuXKt+EkeihTCIgYMllXusuWsISdlQ8xOmbfttEuVeJQzjMvTdIfdqL9QafrTCz1sDA
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:true
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs8bI76FijKKlxMCQPpGjmbIHjOOGEWRtinbMIpgf8cLOuplAiyLPLwoHUobp1HodUwytfBv4+vhEhm+dADVxjIsPg0efENK5sdjRC2oIYTxGNEMbZDKLsJy4lHDgFjTd1ygzASuj4vJowMEJqT92VqCzRW/Kq2MFTNLBRGCmywsX+KE7dMsGqBjF99rm9zd1UJ3LxQ4UycJCzj0ejmv+IL4I//qh6w8tpQiAVNlGTr72rnKApIHAMIG9oAMCARGigbUEgbJ01EPJDRPDnsEsc12hKVghuWkGH6ZU+4UKVFhOSje7/5c7ZpF6FX6QHXHo95gYXgQVWrWA+uMJ600cn9R5JWrTHoKfooEGxAAsLCu7xT+WBO1vRGS0RskOnPGaOFpuGrYiSNlFrMjdbScP8yoO6WUGNZZArULbvFvAHfaxQmWGLwTZ6By1KhNsXFZzO/q5BxAZiZfXab5C+8k5Pb03iah0HgxvI++r6G0e2WDvjM/ErsyE
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834871440
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834871440, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834871440, pContext=0, pDelegCred=0
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Na readline: PROCISFUN:false
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3Q0Gwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs8I0eKoh5D3sZeX6M3ec46AmyLrO5JXBww8xCuX5MUsZM5FdMsZU9EGU/pOqx8J3U3rlB36ApelWALpxcBhn4lfdZ3MKM1YGbfgF6c62AFRwjXwVKubtl4zdPXsFt7miUMyzD+VRNbxcPwzBB0HPmGLx0/fX/SDkAHV1Q3EM8GOTVmrn7e/eEqBaWqmRBN4GUtoYqHlioltlWUOydwSgD1g49XWRsh24/+/ObYZT61GovqvEpIHAMIG9oAMCARGigbUEgbI/ws7VKSDnJg4JU5fsmmjNTNxpeHrFo0plilMMVeDHCink57rdAiSyleaP9Em4aS9DitUxmLufYb4FzxCq5Hv2/VP1XaTPyuyopgi9YAyrrKDXLCa8eG+wP/KfJUaZvEhRrxdOVgWsHyHJWpq74KMhDKyThrTukpPOM5Q7YS+HhRGyXdY+D6BB4giciDSTAFOHUM6tkWXzlnD9MBZhBj5n8WCgnSZAgR9+4zEulPkfbOmc
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834802352
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834802352, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834802352, pContext=4395834802944, pDelegCred=0
18:37:37  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834802944
18:37:37  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834876592
18:37:37  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834876592
18:37:37  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834802944, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834768864
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834768864
18:37:37  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834802352
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834768864, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834802352
18:37:37  PROC: Nb readline: PROCISFUN:true
18:37:37  PROC: Nb println: TEST
18:37:37  PROC: Nb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QzGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1zawk0ps+1ihXna7YFuStMWRA7g4xftQQVuGtKtTPWQXm/u8ng4npiuvDGfIALaRrCM6Lm8VnxB5Rr14C3ySddXUmorxAcbXu2Ue1ZgGAwCvT/RNrkV6detgdA0VDepHwEq/KYcTMi3+5JpXYlzX6TrsM8KyATPmJ5jwS3JCPF3HCIldjaWlkhccXV1YM3LJPSZWOlM5Nqkb1Gupd0C97rphtNmH5nNloHqfwLsH3ppyiwjpIHAMIG9oAMCARGigbUEgbILn/EIC/zWEax4zmHwBwvMslc8jhc1sBFqRLe68vZ7MSCp5Kv32q+TS8oknERzGncyDjBUUnuQZpaoVMOz4gLga7YaLHAp3oj/i9Gz/rvuG22mwoExDfMvS5sSTfE9yyFucToIfAfUHHwNuuFo1LbElkEJRxZHmT/o2rmQbT0KaUnTdhiZIUQ3K+t8Vf7bI1KFZ/NoaT6x0wwTxCyI+jLrdyfMxylfrUkuASqgICUlZGZE
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834888688
18:37:37  PROC: Nb readline: * call acceptSecContext
18:37:37  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Nb readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834888688, pContext=0
18:37:37  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834888688, pContext=4395834800544, pDelegCred=0
18:37:37  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=4395834800544
18:37:37  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834913360
18:37:37  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=4395834913360
18:37:37  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
18:37:37  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
18:37:37  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 4395834800544, isSrc=0
18:37:37  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=4395834925136
18:37:37  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 4395834925136
18:37:37  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 4395834888688
18:37:37  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=4395834925136, usage=2
18:37:37  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834888688
18:37:37  PROC: Nb readline: PROCISFUN:true
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QzGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs5o6I/bfG7VWouFTHXHTcKZdve7ppH3Jda7LTuInjUOScHZU9FwEdq0PRMHqj2uICoiak75gnkuuvW/WI6C9YnW+d+EwgjYXGi4k3TQDmSQDR+4Q4RhXIJg1C2FBE/ZMZhwhBSj/U2pKNY++HlbQF+InygWiFfOmhbaBN1lPpxPlROGEK9ZN0dT8BPaPBgwRug2T1vFzOGZ2HWCUZBl06xOorRnNbWiREdd6Z8Q0IIm1cs9xpIHAMIG9oAMCARGigbUEgbKBr1yjsKgWyaMDASgGnUmj86NsSHn5R4dwqhwx4q7mlLOzuaNEcBdXKKJHcfx+KwTncduXIZrY8kU5aIlnMReFzWOPvGb9mFbz2P38M0DhwIVwBTbwzNfv8fAuIHagAAVzb5KKdl3Gpw5kU4FllZsnjmyCwzf9pMvcUUk6e4dpyWv404zpYBBNZ/UrqllugIAElrHT9plpo0DLmLyIu+HyHmbV+XWWJQ+S3aDz2LCYy2ig
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:false
18:37:37  PROC: Na println: TEST
18:37:37  PROC: Na println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBsw3u+c5dHPMZGF/z+uzz/cfXbIrzSWbIoT6jqHXo5NMPHNka9Ei6LE9wI8F1tLzvlPgDPvcCHKddo2aBzfpZVllyD5IKMH4RSec1eE9z/h5e4X2XvVbzt07a0MGAK0BVbSaggkOcqP7Mg4SYx7FQAOrONLPF8Cz0cwYbN7xY5u88j2c9FOr2iBFZyeZTtmXkLn3v1ZIlkScnO34v6qv6P8aHpeuRSYSVwkZGLZUNFIFYlmpYpIHAMIG9oAMCARGigbUEgbIvGiHkW6aavJewai15ASh0zU/TE+kBt534bCk30vikdEwoQK6/9V9CeU7dthH+v21i/WQ7oxq58ZKmmOC7wAG4qex+4SNgUDujHmBauS2VhXmHEXekFPTxXxXPbVhQPJxxRIdLw3J1LrhbF5SiFQcIA7g/vDal05jSRZ47tJsXCqpdT9qQ+AljpclFjav+yAqZNHMeptLPI7R6FxP8ILfulNJo42R4yg9BrOJdrtENQjz0
18:37:37  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
18:37:37  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
18:37:37  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=4395834899616
18:37:37  PROC: Na readline: * call acceptSecContext
18:37:37  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 480
18:37:37  PROC: Na readline: SunNativeGSS: Complete Token length: 495
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=495
18:37:37  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
18:37:37  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=4395834899616, pContext=0
18:37:37  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=4395834899616, pContext=0, pDelegCred=0
18:37:37  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=106
18:37:37  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
18:37:37  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
18:37:37  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
18:37:37  PROC: Na readline: PROCISFUN:false
18:37:37  PROC: Jb println: TEST
18:37:37  PROC: Jb println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBszxmvzRHvm2njATOhq/PyW/m7Kv65SsAkJSA5WVNGZrdRVl2PsShtUqEiYxpCc1PTmoDUgEFAJdoicq9cappP2Vea4A9r2beoNnsB4fY+zHq81g4+lnB8AaXVpW4srUfjBrSpgIeCaDeG7aw30OSZDO8Gle6ROBDd/VmSoi6YXqE/a8gtsWDFLoAoEdQKNk89P5pnvZvs/69GtpZT4t+3yfUL2pI6bhfOCxaLEvnpMdfVQIapIHAMIG9oAMCARGigbUEgbJju9/yll6P1YwkZIsOiTP9KFJo7I8dE3jxNlo66My83d8eKAUYLtstrIhg0AJuYRi3OCBezDRZTIt0OVXnZyIf12nfIx+RAbjqzeJT5oQxhylJ8sQVJWg1nhnfPSHfRIvvg4xeTIJER4Eb3Rx2d1JbrCIT6KU2L3bWGB8wx8KCeAxWtjdrX6i3FRD6Niz+JFaK8lzLJ63j/yBmVwy1KKDXuyvKHd+t6XGmOsYUE7QEijsI
18:37:37  PROC: Jb readline: * call acceptSecContext
18:37:37  PROC: Jb readline: PROCISFUN:false
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QwGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBs1rN8IxGeqe7WQ7DR9eTeK3IyPcgWxdmKtYYH3nWfSpOFB5htQT1cdL65g9FynMk388H2hkA/4u/Djw7bvLtPv6fyEjg4wnb/Zn3dMBzi0UJoNRPsPnGRSMq3A3hcTuu6RvGVzpKMWNOWh2GTWlsLmFA4HGAKBW2+1zNwro/Yp4YEZ3p3FplCrgisUM9KNtnVLXv3qx9sdxADIzIprwOLJ1IUF2gyGPzPgfHSy/OBhpaEcZHpIHAMIG9oAMCARGigbUEgbLRpjD7LpIIxlzW/mc5454JxpGj4mEDzIoNrSmK4UHOelGK/jcZNvn+yD3Ty9QkNEI2o+7vX62MVsPZveMsBAS5UJQCJAbHTGIyl0NH0WbuW7SaKg1Kp4ORROE5O98eo/Ty43pKqbYpVjrVf7UJLV5Y9oMwADZe+HBAoJVT/nvtMO3t1TO4ICRa0o/z+36eAey8ZH4aM9BQF0r/HaKWymwEBAWGY1nh1iPBmx82lMohjO7W
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:true
18:37:37  PROC: Ja println: TEST
18:37:37  PROC: Ja println: YIIB6wYJKoZIhvcSAQICAQBuggHaMIIB1qADAgEFoQMCAQ6iBwMFACAAAACjgf1hgfowgfegAwIBBaENGwtSQUJCSVQuSE9MRaIdMBugAwIBAKEUMBIbBWhvc3QxGwlsb2NhbGhvc3SjgcEwgb6gAwIBEaKBtgSBsyp3VrjTn8BCmfLJQ2x8Wm+WExitqRYuuWNHBDcNdfpC28t5xz2a1/JuIl7LwtVVBUSJE5WFl2JJKXrFR0+NExqWYjjpcDMccVwCZA2Vsc+9PkDiWDJmK4k7hte7PdUM9uWI3RcOLCkxGpGakEWq7Z2g02ie9tOoKfRQjLOaQCFA9dpNnlswdEnBV1m+zU0Ieg9s2Z4hldGZaYflcNrDzbWN0diDsSYScBQc2UFfOm33mj2bpIHAMIG9oAMCARGigbUEgbL/M43tn0he+J/9FMl4NdgW0ugUy7Sx22gNagRywzIOKwRJTt2JL8Fn3BRby2dy5YUs/9p7ZFTXssLaETMozxIMpTOJM0uZoWKmHOn3kJjAK7U075MSAtJdgje+g3Yb1CGjTnXCuxAG3PtokYl7tbKkKLnAaopvxqeDSL/Gk10zIsVyio7lU7XGS2DwrZbV52DjJVvWgipBXZP2N7icd8lIrZdWRvWlP1TjX9kmEgZpjrjI
18:37:37  PROC: Ja readline: * call acceptSecContext
18:37:37  PROC: Ja readline: PROCISFUN:false
18:37:37  PROC: C println: END
18:37:37  PROC: Ja println: END
18:37:37  PROC: Jb println: END
18:37:37  PROC: Na println: END
18:37:37  PROC: Nb println: END
18:37:37  
18:37:37  All Test Results
18:37:37  ================
18:37:37          req**  client    service  acceptor   Result
18:37:37  ----  -------  ------  ---------  --------  -------
18:37:37    0:      0**      c1    s2  (2)        Na   ++     
18:37:37    1:      0        c1    s2  (2)        Jb   --     
18:37:37    2:      1**      c2    s2  (4)        Nb   ++     
18:37:37    3:      2**      c1    s0  (2)        Nb   ++     
18:37:37    4:      3**      c4    s3  (2)        Ja   ++     
18:37:37    5:      1        c2    s2  (4)        Nb   --     
18:37:37    6:      3        c4    s3  (2)        Nb   --     
18:37:37    7:      4**      c1    s0  (4)        Nb   ++     
18:37:37    8:      2        c1    s0  (4)        Ja   --     
18:37:37    9:      3        c4    s3  (2)        Ja   --     
18:37:37   10:      5**      c4    s3  (4)        Ja   ++     
18:37:37   11:      2        c1    s0  (4)        Ja   --     
18:37:37   12:      5        c4    s3  (4)        Na   --     
18:37:37   13:      1        c2    s2  (4)        Na   --     
18:37:37   14:      6**      c2    s4  (2)        Nb   ++     
18:37:37   15:      3        c4    s3  (4)        Na   --     
18:37:37   16:      7**      c1    s2  (6)        Nb   ++     
18:37:37   17:      4        c1    s0  (4)        Na   --     
18:37:37   18:      1        c2    s2  (6)        Jb   --     
18:37:37   19:      1        c2    s2  (6)        Jb   --     
18:37:37   20:      8**      c1    s4  (4)        Jb   ++     
18:37:37   21:      7        c1    s2  (6)        Jb   --     
18:37:37   22:      2        c1    s0  (4)        Ja   --     
18:37:37   23:      9**      c0    s0  (6)        Ja   ++     
18:37:37   24:     10**      c3    s4  (6)        Jb   ++     
18:37:37   25:     10        c3    s4  (6)        Nb   --     
18:37:37   26:      0        c1    s2  (6)        Nb   --     
18:37:37   27:     11**      c3    s1  (2)        Nb   ++     
18:37:37   28:      6        c2    s4  (6)        Ja   --     
18:37:37   29:     10        c3    s4  (6)        Ja   --     
18:37:37   30:      1        c2    s2  (6)        Na   --     
18:37:37   31:     12**      c3    s2  (8)        Ja   ++     
18:37:37   32:     13**      c0    s2 (10)        Nb   ++     
18:37:37   33:     14**      c4    s2 (12)        Na   ++     
18:37:37   34:     15**      c2    s1  (4)        Na   ++     
18:37:37   35:      9        c0    s0  (6)        Ja   --     
18:37:37   36:      0        c1    s2 (12)        Nb   --     
18:37:37   37:      7        c1    s2 (12)        Ja   --     
18:37:37   38:     16**      c1    s4  (8)        Nb   ++     
18:37:37   39:     17**      c3    s3  (6)        Nb   ++     
18:37:37   40:      7        c1    s2 (12)        Ja   --     
18:37:37   41:     18**      c1    s2 (14)        Na   --  xxx
18:37:37   42:     19**      c0    s2 (16)        Ja   ++     
18:37:37   43:     20**      c1    s0  (8)        Nb   ++     
18:37:37   44:     21**      c3    s0 (10)        Jb   ++     
18:37:37   45:     22**      c4    s3  (8)        Jb   ++     
18:37:37   46:     12        c3    s2 (16)        Nb   --     
18:37:37   47:      2        c1    s0 (10)        Jb   --     
18:37:37   48:      6        c2    s4  (8)        Ja   --     
18:37:37   49:     23**      c1    s1  (6)        Ja   ++     
18:37:37   50:     24**      c3    s1  (8)        Na   ++     
18:37:37   51:     15        c2    s1  (8)        Nb   --     
18:37:37   52:     25**      c3    s3 (10)        Na   ++     
18:37:37   53:     26**      c2    s4 (10)        Nb   ++     
18:37:37   54:     20        c1    s0 (10)        Na   --     
18:37:37   55:      3        c4    s3 (10)        Na   --     
18:37:37   56:     27**      c1    s2 (18)        Nb   ++     
18:37:37   57:     28**      c0    s1 (10)        Nb   ++     
18:37:37   58:     29**      c4    s2 (20)        Ja   ++     
18:37:37   59:     14        c4    s2 (20)        Nb   --     
18:37:37   60:     26        c2    s4 (10)        Ja   --     
18:37:37   61:     30**      c3    s4 (12)        Ja   ++     
18:37:37   62:     23        c1    s1 (10)        Nb   --     
18:37:37   63:     25        c3    s3 (10)        Jb   --     
18:37:37   64:     31**      c0    s1 (12)        Jb   ++     
18:37:37   65:     16        c1    s4 (12)        Ja   --     
18:37:37   66:      8        c1    s4 (12)        Ja   --     
18:37:37   67:     17        c3    s3 (10)        Ja   --     
18:37:37   68:      7        c1    s2 (20)        Nb   --     
18:37:37   69:     32**      c1    s2 (22)        Nb   ++     
18:37:37   70:     31        c0    s1 (12)        Na   --     
18:37:37   71:     24        c3    s1 (12)        Na   --     
18:37:37   72:     33**      c3    s0 (12)        Na   ++     
18:37:37   73:     34**      c4    s0 (14)        Nb   ++     
18:37:37   74:      1        c2    s2 (22)        Nb   --     
18:37:37   75:     28        c0    s1 (12)        Jb   --     
18:37:37   76:      2        c1    s0 (14)        Jb   --     
18:37:37   77:     35**      c2    s2 (24)        Ja   ++     
18:37:37   78:     24        c3    s1 (12)        Na   --     
18:37:37   79:     25        c3    s3 (10)        Ja   --     
18:37:37   80:     36**      c4    s1 (14)        Ja   ++     
18:37:37   81:     37**      c2    s2 (26)        Na   ++     
18:37:37   82:     38**      c1    s2 (28)        Na   ++     
18:37:37   83:     39**      c0    s1 (16)        Jb   ++     
18:37:37   84:     39        c0    s1 (16)        Na   --     
18:37:37   85:     40**      c1    s1 (18)        Nb   ++     
18:37:37   86:     41**      c0    s1 (20)        Ja   ++     
18:37:37   87:     42**      c3    s2 (30)        Ja   ++     
18:37:37   88:     43**      c1    s3 (12)        Nb   ++     
18:37:37   89:     23        c1    s1 (20)        Ja   --     
18:37:37   90:      9        c0    s0 (14)        Jb   --     
18:37:37   91:     44**      c1    s2 (32)        Jb   ++     
18:37:37   92:     28        c0    s1 (20)        Na   --     
18:37:37   93:     45**      c4    s4 (14)        Nb   ++     
18:37:37   94:     46**      c2    s3 (14)        Nb   ++     
18:37:37   95:      5        c4    s3 (14)        Ja   --     
18:37:37   96:     31        c0    s1 (20)        Na   --     
18:37:37   97:     20        c1    s0 (14)        Jb   --     
18:37:37   98:     47**      c0    s0 (16)        Ja   ++     
18:37:37   99:     40        c1    s1 (20)        Ja   --     
18:37:37  
18:37:37  Path of Reqs
18:37:37  ============
18:37:37    0 (c1 -> s2): Na(0) -> Jb(1) -> Nb(26) -> Nb(36)
18:37:37    1 (c2 -> s2): Nb(2) -> Nb(5) -> Na(13) -> Jb(18) -> Jb(19) -> Na(30) -> Nb(74)
18:37:37    2 (c1 -> s0): Nb(3) -> Ja(8) -> Ja(11) -> Ja(22) -> Jb(47) -> Jb(76)
18:37:37    3 (c4 -> s3): Ja(4) -> Nb(6) -> Ja(9) -> Na(15) -> Na(55)
18:37:37    4 (c1 -> s0): Nb(7) -> Na(17)
18:37:37    5 (c4 -> s3): Ja(10) -> Na(12) -> Ja(95)
18:37:37    6 (c2 -> s4): Nb(14) -> Ja(28) -> Ja(48)
18:37:37    7 (c1 -> s2): Nb(16) -> Jb(21) -> Ja(37) -> Ja(40) -> Nb(68)
18:37:37    8 (c1 -> s4): Jb(20) -> Ja(66)
18:37:37    9 (c0 -> s0): Ja(23) -> Ja(35) -> Jb(90)
18:37:37   10 (c3 -> s4): Jb(24) -> Nb(25) -> Ja(29)
18:37:37   11 (c3 -> s1): Nb(27)
18:37:37   12 (c3 -> s2): Ja(31) -> Nb(46)
18:37:37   13 (c0 -> s2): Nb(32)
18:37:37   14 (c4 -> s2): Na(33) -> Nb(59)
18:37:37   15 (c2 -> s1): Na(34) -> Nb(51)
18:37:37   16 (c1 -> s4): Nb(38) -> Ja(65)
18:37:37   17 (c3 -> s3): Nb(39) -> Ja(67)
18:37:37   18 (c1 -> s2): Na(41)xxx
18:37:37   19 (c0 -> s2): Ja(42)
18:37:37   20 (c1 -> s0): Nb(43) -> Na(54) -> Jb(97)
18:37:37   21 (c3 -> s0): Jb(44)
18:37:37   22 (c4 -> s3): Jb(45)
18:37:37   23 (c1 -> s1): Ja(49) -> Nb(62) -> Ja(89)
18:37:37   24 (c3 -> s1): Na(50) -> Na(71) -> Na(78)
18:37:37   25 (c3 -> s3): Na(52) -> Jb(63) -> Ja(79)
18:37:37   26 (c2 -> s4): Nb(53) -> Ja(60)
18:37:37   27 (c1 -> s2): Nb(56)
18:37:37   28 (c0 -> s1): Nb(57) -> Jb(75) -> Na(92)
18:37:37   29 (c4 -> s2): Ja(58)
18:37:37   30 (c3 -> s4): Ja(61)
18:37:37   31 (c0 -> s1): Jb(64) -> Na(70) -> Na(96)
18:37:37   32 (c1 -> s2): Nb(69)
18:37:37   33 (c3 -> s0): Na(72)
18:37:37   34 (c4 -> s0): Nb(73)
18:37:37   35 (c2 -> s2): Ja(77)
18:37:37   36 (c4 -> s1): Ja(80)
18:37:37   37 (c2 -> s2): Na(81)
18:37:37   38 (c1 -> s2): Na(82)
18:37:37   39 (c0 -> s1): Jb(83) -> Na(84)
18:37:37   40 (c1 -> s1): Nb(85) -> Ja(99)
18:37:37   41 (c0 -> s1): Ja(86)
18:37:37   42 (c3 -> s2): Ja(87)
18:37:37   43 (c1 -> s3): Nb(88)
18:37:37   44 (c1 -> s2): Jb(91)
18:37:37   45 (c4 -> s4): Nb(93)
18:37:37   46 (c2 -> s3): Nb(94)
18:37:37   47 (c0 -> s0): Ja(98)
18:37:37  
18:37:37  STDERR:
18:37:37  Nsanity started
18:37:37  Ja started
18:37:37  Jb started
18:37:37  Na started
18:37:37  Nb started
18:37:37  java.lang.Exception
18:37:37  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:286)
18:37:37  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:333)
18:37:37  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
18:37:37  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
18:37:37  	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
18:37:37  	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
18:37:37  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
18:37:37  	at java.base/java.lang.Thread.run(Thread.java:836)
18:37:37  java.lang.Exception
18:37:37  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:286)
18:37:37  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:333)
18:37:37  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
18:37:37  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
18:37:37  	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
18:37:37  	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
18:37:37  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
18:37:37  	at java.base/java.lang.Thread.run(Thread.java:836)
18:37:37  
18:37:37  JavaTest Message: Test threw exception: java.lang.Exception
@pshipton
Copy link
Member

@andrew-m-leonard similar to #12176, does this also fail with Hotspot now?

@andrew-m-leonard
Copy link
Contributor Author

Yes last passed on Hotspot: https://ci.adoptopenjdk.net/view/Test_openjdk/job/Test_openjdk11_hs_extended.openjdk_s390x_linux/16/testReport/sun_security_krb5_auto_rcache_usemd5/sh/rcache_usemd5/
although that was 2 weeks ago, so i've re-submitted again on a hs nightly on the same node as above: https://ci.adoptopenjdk.net/job/Grinder/7597/console

@andrew-m-leonard
Copy link
Contributor Author

Passes on latest Hotspot nightly: https://ci.adoptopenjdk.net/job/Grinder/7597/
@pshipton fyi

@pshipton
Copy link
Member

An intermittent failure? Pls grind it to get a failure rate. Has it been seen on any other platforms besides zlinux? Pls try a grinder with -Xint as well.

@JasonFengJ9
Copy link
Member

Also seen in an internal build Test_openjdk8_j9_extended.openjdk_s390x_linux/3

23:34:45  ===============================================
23:34:45  Running test jdk_security4_0 ...
23:34:45  ===============================================
23:34:45  jdk_security4_0 Start Time: Fri Mar 12 19:34:44 2021 Epoch Time (ms): 1615606484367
23:34:45  "/home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:destroyAll; "/home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:groupAccess,destroyAll; echo "cache cleanup done";
23:34:45  JVMSHRC005I No shared class caches available
23:34:45  JVMSHRC005I No shared class caches available
23:34:45  cache cleanup done
23:34:45  variation: Mode150
23:34:45  JVM_OPTIONS:  -XX:+UseCompressedOops 

23:36:23  --------------------------------------------------
23:36:23  TEST: sun/security/krb5/auto/ReplayCacheTestProc.java
23:36:23  TEST JDK: /home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_s390x_linux/openjdkbinary/j2sdk-image
23:36:23  
23:36:23  Console resue old
23:36:23  Console send to 4
23:36:23  Console sees false
23:36:23  java.lang.Exception
23:36:23  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:174)
23:36:23  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:217)
23:36:23  	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
23:36:23  	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
23:36:23  	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
23:36:23  	at java.lang.reflect.Method.invoke(Method.java:498)
23:36:23  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
23:36:23  	at java.lang.Thread.run(Thread.java:823)
23:36:23  java.lang.Exception
23:36:23  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:174)
23:36:23  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:217)
23:36:23  	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
23:36:23  	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
23:36:23  	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
23:36:23  	at java.lang.reflect.Method.invoke(Method.java:498)
23:36:23  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
23:36:23  	at java.lang.Thread.run(Thread.java:823)
23:36:23  
23:36:23  JavaTest Message: Test threw exception: java.lang.Exception
23:36:23  JavaTest Message: shutting down test
23:36:23  
23:36:23  STATUS:Failed.`main' threw exception: java.lang.Exception
23:36:23  TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.Exception
23:36:23  --------------------------------------------------
23:37:14  Test results: passed: 134; failed: 1
23:37:18  Report written to /home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_s390x_linux/jvmtest/openjdk/report/html/report.html
23:37:18  Results written to /home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_s390x_linux/openjdk-tests/TKG/output_16156037212340/jdk_security4_0/work
23:37:18  Error: Some tests failed or other problems occurred.
23:37:18  
23:37:18  jdk_security4_0_FAILED

@andrew-m-leonard
Copy link
Contributor Author

Grinder x10 : https://ci.adoptopenjdk.net/job/Grinder/7729/
Grinder x10 -Xint : https://ci.adoptopenjdk.net/job/Grinder/7730/

@andrew-m-leonard
Copy link
Contributor Author

Grinder x10 : https://ci.adoptopenjdk.net/job/Grinder/7729/
Grinder x10 -Xint : https://ci.adoptopenjdk.net/job/Grinder/7730/
@pshipton All Green

@pshipton pshipton added this to the JTReg Backlog milestone Mar 16, 2021
@pshipton
Copy link
Member

Not sure what that means. Is the problem resolved now, or just more intermittent than the number of tries.

@andrew-m-leonard
Copy link
Contributor Author

Yeah, me neither! i've submitted a 50x grinder : https://ci.adoptopenjdk.net/job/Grinder/7820/

@andrew-m-leonard
Copy link
Contributor Author

So from grinder https://ci.adoptopenjdk.net/job/Grinder/7820/, 1 failure out of 100

@pshipton
Copy link
Member

Pls try a large number of iterations with Hotspot as well to see if the same failure can be reproduced.

@andrew-m-leonard
Copy link
Contributor Author

https://ci.adoptopenjdk.net/job/Grinder/7840/

@andrew-m-leonard
Copy link
Contributor Author

@pshipton 50x succeeds with hotspot: https://ci.adoptopenjdk.net/job/Grinder/7840/

@lumpfish
Copy link
Contributor

Also seen on jdk11 linux_x86-64:

openjdk version "11.0.11" 2021-04-20
OpenJDK Runtime Environment AdoptOpenJDK (build 11.0.11+7)
Eclipse OpenJ9 VM AdoptOpenJDK (build master-1087b0015, JRE 11 Linux amd64-64-Bit Compressed References 20210320_953 (JIT enabled, AOT enabled)
OpenJ9   - 1087b0015
OMR      - f111a8bd2
JCL      - 322b40f539 based on jdk-11.0.11+7)

22:03:45  STDOUT:
22:03:45  Start KDC on 16390
22:03:45  PROC: Nsanity cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Nsanity]
22:03:45  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Nsanity 
22:03:45  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi.so
22:03:45  PROC: Nsanity readline: [GSSLibStub:110] libgssapi.so: cannot open shared object file: No such file or directory
22:03:45  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi_krb5.so
22:03:45  PROC: Nsanity readline: [GSSLibStub:110] libgssapi_krb5.so: cannot open shared object file: No such file or directory
22:03:45  PROC: Nsanity readline: [GSSLibStub:68] [GSSLibStub_init] libName=libgssapi_krb5.so.2
22:03:45  PROC: Nsanity readline: SunNativeGSS: Loaded GSS library: libgssapi_krb5.so.2
22:03:45  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.2.840.113554.1.2.2
22:03:45  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.3.6.1.5.2.5
22:03:45  PROC: Nsanity readline: SunNativeGSS: Native MF for 1.3.6.1.5.5.2
22:03:45  PROC: Nsanity readline: SunNativeGSS: Created GSSLibStub for mech 1.2.840.113554.1.2.2
22:03:45  PROC: Nsanity readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nsanity readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nsanity readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140037078517664
22:03:45  PROC: Nsanity readline: <EOF>
22:03:45  PROC: C cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, ReplayCacheTestProc, initiator]
22:03:45  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts ReplayCacheTestProc initiator 
22:03:45  PROC: Ja cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Dsun.security.krb5.rcache=dfl, -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2, -Djdk.net.hosts.file=TestHosts, ReplayCacheTestProc, Ja]
22:03:45  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Dsun.security.krb5.rcache=dfl -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2 -Djdk.net.hosts.file=TestHosts ReplayCacheTestProc Ja 
22:03:45  PROC: Jb cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Dsun.security.krb5.rcache=dfl, -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2, -Djdk.net.hosts.file=TestHosts, ReplayCacheTestProc, Jb]
22:03:45  /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Dsun.security.krb5.rcache=dfl -Djava.io.tmpdir=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2 -Djdk.net.hosts.file=TestHosts ReplayCacheTestProc Jb 
22:03:45  PROC: Na cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Na]
22:03:45  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Na 
22:03:45  PROC: Nb cmdline: [/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java, --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED, --add-exports=java.base/sun.security.util=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED, --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED, -ea, -esa, -Xmx512m, -XX:+UseCompressedOops, -cp, /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib, -Djdk.net.hosts.file=TestHosts, -Dsun.security.jgss.native=true, -Dsun.security.nativegss.debug=true, -Djavax.security.auth.useSubjectCredsOnly=false, ReplayCacheTestProc, Nb]
22:03:45  KRB5_KTNAME=localkdc.ktab KRB5_CONFIG=localkdc-krb5.conf KRB5RCACHEDIR=/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/scratch/2 /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdkbinary/j2sdk-image/bin/java --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED --add-exports=java.base/sun.security.util=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.jgss.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-opens=java.security.jgss/sun.security.krb5.internal=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ccache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.rcache=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.crypto=ALL-UNNAMED --add-exports=java.security.jgss/sun.security.krb5.internal.ktab=ALL-UNNAMED -ea -esa -Xmx512m -XX:+UseCompressedOops -cp /home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/sun/security/krb5/auto/ReplayCacheTestProc.d:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/TKG/output_16162700362204/jdk_security4_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/sun/security/krb5/auto:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/jdk/java/security/testlibrary:/home/jenkins/workspace/Test_openjdk11_j9_extended.openjdk_x86-64_linux/openjdk-tests/openjdk/openjdk-jdk/test/lib -Djdk.net.hosts.file=TestHosts -Dsun.security.jgss.native=true -Dsun.security.nativegss.debug=true -Djavax.security.auth.useSubjectCredsOnly=false ReplayCacheTestProc Nb 
22:03:45  PROC: C println: client3 service0/localhost
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client3@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45  KrbException: Additional pre-authentication required (25)
22:03:45  	at KDC.processAsReq(KDC.java:1294)
22:03:45  	at KDC.processMessage(KDC.java:772)
22:03:45  	at KDC$1.run(KDC.java:1524)
22:03:45       Error 25 Additional pre-authentication required
22:03:45  >>>>> UDP request honored
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client3@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45       Return client3@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: client3 call initSecContext
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> null sends TGS-REQ for service0/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
22:03:45  RABBIT.HOLE> verifying referral for service0/localhost
22:03:45  RABBIT.HOLE> presenting a ticket of client3@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
22:03:45       Return client3@RABBIT.HOLE ticket for service0/localhost@RABBIT.HOLE, flags INITIAL
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: PROCISFUN:YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbMDMYh6nnoVV3BnPtvdhcZszzjcCqfxRy9wtR7pNWgp1BElodDLfoZtEtqmlm4XF1f8eDYxg9j6uTrwFUP3fl4VdmxzmrraDiQBdlu/6x0OlOO3d1Z8090Bu6IG2ufR+cdIo7J+v2SLawan/kxnjbtyPqBtC06BuMwBGEBAGyAF1ny41haZ3HCdPB0SqsS9whTRAjclIuuim8GIuMeImrT3DFnVak/nvnAzAuxSOKCI50/lf6SBwDCBvaADAgERooG1BIGylNIIqbJPJFUDiA7WyNq1VH0/fH31K3wPOvJRMyZbbyG5KGoAX8qyhZbh66SfkvpClrXksIzdO84XV339SaAK/A9w7ixN0LBC32jqfaGgbwungnB0A+t2QLdr5UIhvCiGjanDL3ZUH9gcQFq3CpeEIVoTT59Dcl3wdi30LmsGNiQ47im/Eeq3gv/Y4nEHrPfTWOPKKMVz5wAsYBQKIMvrMhhZLCPihNMG24xNDo52HDv6pA==
22:03:45  PROC: C println: client2 service0/localhost
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client2@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45  KrbException: Additional pre-authentication required (25)
22:03:45  	at KDC.processAsReq(KDC.java:1294)
22:03:45  	at KDC.processMessage(KDC.java:772)
22:03:45  	at KDC$1.run(KDC.java:1524)
22:03:45       Error 25 Additional pre-authentication required
22:03:45  >>>>> UDP request honored
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client2@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45       Return client2@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: client2 call initSecContext
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> null sends TGS-REQ for service0/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
22:03:45  RABBIT.HOLE> verifying referral for service0/localhost
22:03:45  RABBIT.HOLE> presenting a ticket of client2@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
22:03:45       Return client2@RABBIT.HOLE ticket for service0/localhost@RABBIT.HOLE, flags INITIAL
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: PROCISFUN:YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOr+mt/3IcPTJcg/xblVLT5lhrsIGWeJx9v49a4L49UpaZxew0fEy7Tmnyhr0ZQKf8+OuBihJdwoOz5TKp5IBwmPj34/W95pRH0opeuXS0OLori6qQ3FhJNlg+Y23tN7Kfu7ZAatJph7+39lEJy7wJCPr4clRQ7nYOXw57JgNcvbYq04Xu+T0X7whJ6RQTULmnvpS6vF0yfn+IiuhUZTw+QQMT5oYVPDE4iTzB4JqYtlMfvYqSBwDCBvaADAgERooG1BIGyN1x/9Wr2cKi7u7qFfpy6R0aNzkg4Hy3xMWIC36Kupk9uKW1/se2gqb0mllmyNfTJNmu/5ia5rCBgFAlvgEfnNcWpxmWnJugCYTYgKvDlSVYwo+uGwhdox4n4228TyLioHaV47+LAD8CrObl7I7uFbVTBmgQRADwyMaOoCVZVmZIisitVzYbNXKWwIy0FHiJuQZRvOgnSaq53g0V2geYWf2OhRgZHU7eLjg2/23HAEhWxow==
22:03:45  PROC: C println: client4 service2/localhost
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client4@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45  KrbException: Additional pre-authentication required (25)
22:03:45  	at KDC.processAsReq(KDC.java:1294)
22:03:45  	at KDC.processMessage(KDC.java:772)
22:03:45  	at KDC$1.run(KDC.java:1524)
22:03:45       Error 25 Additional pre-authentication required
22:03:45  >>>>> UDP request honored
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client4@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45       Return client4@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: client4 call initSecContext
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> null sends TGS-REQ for service2/localhost@RABBIT.HOLE, KDCOptions: CANONICALIZE,
22:03:45  RABBIT.HOLE> verifying referral for service2/localhost
22:03:45  RABBIT.HOLE> presenting a ticket of client4@RABBIT.HOLE to krbtgt/RABBIT.HOLE@RABBIT.HOLE
22:03:45       Return client4@RABBIT.HOLE ticket for service2/localhost@RABBIT.HOLE, flags INITIAL
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: PROCISFUN:YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbO/Yuz1BxJsrBTcEYv3uQ3OKkJPamQr8QIWHVFJIntQ1/z5eS9+kcse3kUyTYlFiQnqD53ooEwWVvlfF7Gj31T5IpqX838LHasSDU24OjdyZt4zPnHnW+5qa02dZ3xQgyqJRNz0cK8WQ9Ff/xulZtzHro2FGkQX9nt1awTlrkSt2RJFylQlbtXF2r1WCCLdy9IAiSh+Tp1SvPGuEbSWiiV18Xm5YlYgSkb3uqEjURjWTkPizaSBwDCBvaADAgERooG1BIGy8zm1hTjopNU6191vPJ1ej9KHcNvXj+xeDthFmXg7cRHwhbZ3+lCq8CxfUdZ1vLjPXJes/kuvMli4a0TwJMx9JtbojoVhkcd8r54a0zkIzHXDR9jUywWTtcWxmwlnJO+sKQyI4L7yO70DhFcfcckxjscIljD6MUaThjJAAE1RebULp+S5POgUmhgNu+M9nqkLbiCEgD1Vf8KMTTGUJHc5SOsiywrQf1RP88zrvwwxI689Ew==
22:03:45  PROC: C println: client3 service1/localhost
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client3@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45  KrbException: Additional pre-authentication required (25)
22:03:45  	at KDC.processAsReq(KDC.java:1294)
22:03:45  	at KDC.processMessage(KDC.java:772)
22:03:45  	at KDC$1.run(KDC.java:1524)
22:03:45       Error 25 Additional pre-authentication required
22:03:45  >>>>> UDP request honored
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> client3@RABBIT.HOLE sends AS-REQ for krbtgt/RABBIT.HOLE@RABBIT.HOLE, KDCOptions: 
22:03:45       Return client3@RABBIT.HOLE ticket for krbtgt/RABBIT.HOLE@RABBIT.HOLE, flags INITIAL;PRE-AUTHENT
22:03:45  >>>>> UDP request honored
22:03:45  PROC: C readline: client3 call initSecContext
22:03:45  -----------------------------------------------
22:03:45  >>>>> UDP packet received
22:03:45  RABBIT.HOLE> null sends TGS-REQ for servi
22:03:45  
22:03:45  ...
22:03:45  Output overflow:
22:03:45  JT Harness has limited the test output to the text
22:03:45  at the beginning and the end, so that you can see how the
22:03:45  test began, and how it completed.
22:03:45  
22:03:45  If you need to see more of the output from the test,
22:03:45  set the system property javatest.maxOutputSize to a higher
22:03:45  value. The current value is 100000
22:03:45  ...
22:03:45  
22:03:45  Stub:366] [GSSLibStub_releaseName] 140435368274960
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435368274560
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369774688
22:03:45  PROC: Na readline: [GSSLibStub:1351] [GSSLibStub_deleteContext] 140435369776240
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369776864
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369793664
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369780496
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369780912
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369623424
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369781024
22:03:45  PROC: Na readline: [GSSLibStub:1351] [GSSLibStub_deleteContext] 140435369841504
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369781488
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369779648
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369850896
22:03:45  PROC: Na readline: [GSSLibStub:1351] [GSSLibStub_deleteContext] 140435369842992
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369842464
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369779760
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369844560
22:03:45  PROC: Na readline: [GSSLibStub:1351] [GSSLibStub_deleteContext] 140435369847088
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369878944
22:03:45  PROC: Na readline: [GSSLibStub:366] [GSSLibStub_releaseName] 140435369846272
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369879360
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435369780816
22:03:45  PROC: Na readline: [GSSLibStub:1351] [GSSLibStub_deleteContext] 140435369882320
22:03:45  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140435369871680
22:03:45  PROC: Na readline: * call acceptSecContext
22:03:45  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Na readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140435369871680, pContext=0
22:03:45  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140435369871680, pContext=0, pDelegCred=0
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=108
22:03:45  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Na readline: PROCISFUN:false
22:03:45  PROC: Na println: TEST
22:03:45  PROC: Na println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbP7/9ezx0sl5Vcq4RbjrPxQAQzxg/CchdApNybWmvg4JOq7DxRP7uCTzkh5prk6bX0ftT2noQ23d+21jcTfph4tXehUOsv+vOZ82hGY7ehmWv5IaUfqJ2sSp4WdJG9IX+K3+fvIE2hRNvs3yut/jN2gR9s/WfxeYmxJxs6ClBlv35NKy+VETqztSgdBf8YdGFLK1hCPPTKv9nF2A1sHP9ezA5v9l0lTbtZikOzeMSDeHrmQyaSBwDCBvaADAgERooG1BIGywsBXPsX32pmYGmLbjhbxalSqUUZaDSAUuupUCI26IJjXD4wiVstmT0dvP4Yj26qA7TBG2OHb8eSaqcf6cbmlqPLk7+n5B60VAHF0BX0c6dMKe4J7ZOczD1sdsZutUvelUVcUzhP9lGJF0Dn3g3LOuFnfv+6IQilk9VnL+ilrVgbIlCwBPBYOHg6YGugrB5v4K49mKbJWHagumP20WeBGKlb5Nj9MgM8dYi7qxs0ZCvjj1Q==
22:03:45  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140435370031600
22:03:45  PROC: Na readline: * call acceptSecContext
22:03:45  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Na readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140435370031600, pContext=0
22:03:45  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140435370031600, pContext=140435369622864, pDelegCred=0
22:03:45  PROC: Na readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=140435369622864
22:03:45  PROC: Na readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
22:03:45  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 140435370020736
22:03:45  PROC: Na readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=140435370020736
22:03:45  PROC: Na readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=138
22:03:45  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 140435369622864, isSrc=0
22:03:45  PROC: Na readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=140435369795520
22:03:45  PROC: Na readline: [GSSLibStub:569] [GSSLibStub_displayName] 140435369795520
22:03:45  PROC: Na readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140435370031600
22:03:45  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=140435369795520, usage=2
22:03:45  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140435369831744
22:03:45  PROC: Na readline: PROCISFUN:true
22:03:45  PROC: Ja println: TEST
22:03:45  PROC: Ja println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbNqAxXK5mVJroiw00dPuzMNPve1Rhzk1YZv0m5ocvqtr8sb6ujkUbM70EU3cNys6NtR0MhpZdCnkUd8KZ248CD396dcc9CsxiMYDjwSmxYGQK6XC+Op+tYOvjphydbk1f5uu3D0gFTSs1CAtFs8Ulvtkt4gZZQz7JRESOgSezZZoDCGVEmnIktIOb4p/R8kN1zoufgox7XgW6tJeaylKfBI85tjOysgfedwoF2Sa0Fm2UoADqSBwDCBvaADAgERooG1BIGy5JTCBjlmnFD3zMxN6paYjnziQq6HCyPM4exVhQyrAlRaaMLSkwc8Io1o57Wte5yInt7fm3Sb0gVgMksQcMOaf4/7q3Z/QYjUSIrXkb+dPfxUt19/RQXeKQpKub3Atl4bXRJKr5XsleGLb4x/HVWEwHM3C7ItW6jQ/Y89YRJUbO8Sg/MGWlpsyZ/Xexue//Iuo8emWssHbioRT9o3suxKW3vPe66HHbWXdFN2uucBrkREVg==
22:03:45  PROC: Ja readline: * call acceptSecContext
22:03:45  PROC: Ja readline: PROCISFUN:true
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMxsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbPkeR65nohyHJwW0zE0de0vH4zjbQ/vHWjKDL+GN+WIB9gRI6E5Wi/jLkpPGNmaflJVbZK0uZUh51zvT8d0qvAIy129KAMrOsWxSGvVoZ1o5PWgMyt7YOIAsVO4YfXY9z4DReYMK624KEdCbNVfnpSD7VSAivdam8958TuuArF3e/wl1y//8qUgxr29tTeLsjnkiUZkhLRK+0zI7FGVu/HuNgIogbFqENIVDoxC8Xu9xarG9aSBwDCBvaADAgERooG1BIGyuj6G2RZBvSWMzear39fg6WxX50Q0SkjwyRxkKLFWFrX3OOTq4kD1DRltjw+gCVpYy2BMnjW7pAwlSBv+Rd8oH4ejRfI+r1FGNKi69YJHNGgoFdwoQGReVembXgEZpSshDmjRcp04rozbnpuetT46LtdNa8F51p9H0HDgDAzSHpaSfAwjhnLPYUdvvyDrDyuWNKZoqnPZ3DTrjs7AVaW6OEFEtsfLlQRpbZFm8650ek/t2g==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623494224
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623494224, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623494224, pContext=0, pDelegCred=0
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=109
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Nb readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Nb readline: PROCISFUN:false
22:03:45  PROC: Jb println: TEST
22:03:45  PROC: Jb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMxsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbPwY9ZlUvA+ObWdJxj25xPxBe+QAFcbitc5lR/CqDF6juIJ0dblfErYTjWm0g+25XN4WM2rWu6UM2rgrKeDg/xqQ5/chfRJ3ksZJUFpErybdX9F52N2gadQDy6gP6MUEf4trRe+pibDjd4wNrpGi+/+opAKczCY9PAiFWIS6uQNP8NCKm5JDAq86G6UpSlj4F0DgkfgbLB1mRToyGYyUhck2xKf5ljHA7XpFM2LZQQt2WZxbaSBwDCBvaADAgERooG1BIGyfh//h8RKi2oVc4WC+wIylhM6FrIJXBa+H+c9mG8NQPeZXsXiVC/Ve0/HM1kgqYj289SVfia3DlrDlBZLY2ssmT3OOoQwS46Hb63EXSZV2WvlL7zgM2u/P+3H2B5FXEOJ1p7+dh157+FwzZ2JYwxJFGj4rZku8PFkGUvnGHzfx0EzcAJ6G9e5hJXrvKUgECOk37lQT1NVV2Wo1K+99BaEKhtnftnzWkV4UdwNJ657lP8gtg==
22:03:45  PROC: Jb readline: * call acceptSecContext
22:03:45  PROC: Jb readline: PROCISFUN:true
22:03:45  PROC: Jb println: TEST
22:03:45  PROC: Jb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMxsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOPaNr1nQ8l8TKCmN9a4qVMJcu8UbEexo2Swt8d59q0wemxsmyyBIaSaunxyZlOjdsapakIstap/KMchmXwwS6Ra0qmPLSnu153YiflbAKYoDs9EphCy4O9eLE9e1bGVUd+323ds+R2vS3Z6iEJhpjaPX1iA8LR7N00Bj4ysc5v4XFREmRFGE4WzcJdoanMlE0FWHnVcStzzejH6GptxWhYDF8j3VNWFuktuX3qulONrTUls6SBwDCBvaADAgERooG1BIGy5AMl8UnVz3iLz7Q3xx8w6GR+pLLAt8aw8ZbbQ6GLPAvte9rnBzkycOjNtwxa5vqMZswoDKTmUAzorEizkfUu2x4vNV1Kg3FnecYRA00SD28z/PVp0qMeim0NE73LsO2FJsh7OzgWhxUh1aFEeKBVXA1xPBnmMWLaB8kptaUxUvLmBFlwjwavv2j8CzSI+3GiN4W7nJVYo3Lz93UHzfMX8Jlr+sPsO+ecwjuLIy6kiph0UQ==
22:03:45  PROC: Jb readline: * call acceptSecContext
22:03:45  PROC: Jb readline: PROCISFUN:true
22:03:45  PROC: Na println: TEST
22:03:45  PROC: Na println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbO6sJJI4Ofzd+gRdrqyov9LyCfujSaqIAi7AemTS/kbKA1bQJ3CpxPOPzTN9iiRF4GRo+wvQmaCk6OcceKP/HHG8OrL5ZkXdvVKgp6hl5CyRPirhgyjCxkNCQdh3RAr7KIc0iQ1sWlzhfAe2Moty5pcQpsd4KCft6tAdG78kBqDORBCn/thFcsyr88OUBZtDGIFp8Xf+hYdkjaTwwm22d7GWWpFEVPB5nWtk85/xC09LUYHDqSBwDCBvaADAgERooG1BIGybl3eFJMX8fq7b4KuOAdyDGEuffxXH+hKqnzgHjdmsl3zogYm3OEDQI/kInCD9KQTt1JskWzcZ74+Rn/NpBRXSoCMENIIzxGcCjVkPicLIhHVw3GLbfygKRIoAP1m28pTW7lmfi2OhUo8n1Q9YY/npF43TplObUQ9u1KSuCbV8UIsbLOE1ZQNpCGCgGvgHcYVMUP7dtfXD+4SrBe692FV5KLm4Nat+XlPALNaW56kitUusg==
22:03:45  PROC: Na readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Na readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Na readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140435370031600
22:03:45  PROC: Na readline: * call acceptSecContext
22:03:45  PROC: Na readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Na readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Na readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Na readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140435370031600, pContext=0
22:03:45  PROC: Na readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140435370031600, pContext=0, pDelegCred=0
22:03:45  PROC: Na readline: SunNativeGSS: acceptSecContext=> outToken len=108
22:03:45  PROC: Na readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Na readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Na readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Na readline: PROCISFUN:false
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMRsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbPVyvN1AF8MkLATNvgeLhp6OeUf8aT0nPCsPlZTyeEIW+rqEhbtUDSmFQ2Svn9WEMi3hEVQRJQnwFjQ+QQ3b+sNi1gakZ6P888nmwXc5mrjLdaLz5fElWvvBg+l9Y72qFJ5o+hBd1pCjBdqu316E9RfGS9bm2NM5sjURXlxiii0qyPXfDjqV8a/YqMlKT1RaRk7sIHr5xLNvKOWSF8sdgLCXKt8CnyKL4GGDEQOfX4+n6GuOqSBwDCBvaADAgERooG1BIGynj15ujU6Am05LR2WBLHin8hV0FuZycNyrhW3Ww8A4HeyruHYYKejOSMj8SabVW4jyugQtdeNhvrLeRJ6Ghinp0AGTFDrroYRouZuwtsNr9mMioubXJ/Yq1g04x8ZMnycNy7wwVama3xnaXGXKsTqit9J+ApLcltjMNE9WIzKaBo1UnTTF0F9KjM8OGIo4HShOjUWY5+8K5fWXfByYK1hZS7hS6EvS5GLQv/ypN6CTSRlpQ==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623442992
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623442992, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623442992, pContext=140109623369440, pDelegCred=0
22:03:45  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=140109623369440
22:03:45  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623442304
22:03:45  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=140109623442304
22:03:45  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 140109623369440, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=140109623497792
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623497792
22:03:45  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140109623442992
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=140109623497792, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623497840
22:03:45  PROC: Nb readline: PROCISFUN:true
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOYZRkyg3mkgky2dWbgOEF25f4AgMlsepNdf1fAbVH0dOEo870GJ8m73nGhxHeaEvxdRjOrp7VpFewsmmWrCSk7iRO1VRi6JmxZAyo/nA1l5UsDfoY/lIMHWHCK3RlA0QMFIgfxj6WwFJgt4LJhJsKcoFfxTkD+8DueUNhQQwRlQh0fXoI2ius2X7HmtXb0q8I6lEjrnjPSKnK9/J6UDddm7wDHo43T3D1QxcdHHbgyIU4AcaSBwDCBvaADAgERooG1BIGybCgeMdff5oJdw4kAIS3+0d05qOgRYe6+LpXAtKSvOpKz/HL44uVfgy2pdeB5q5lQ0o4HOVajMk82PF9JuGvczJDYxGy0aOGd2BGF7soNWC4JHucziVXb6pFFf1lHQcKQI5jcHCWeOmGL6NCFOUUfjDIk5KrMe/zPCgsViog4TmHveLi2CwT3R3WflcDjkRt2MbkyYVN40Fdtc0yQ+N+6vVfjoaxu7X9wpxNEJ9QcVbNmjQ==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623442992
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623442992, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623442992, pContext=0, pDelegCred=0
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=109
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Nb readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Nb readline: PROCISFUN:false
22:03:45  PROC: Jb println: TEST
22:03:45  PROC: Jb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMRsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbPVyvN1AF8MkLATNvgeLhp6OeUf8aT0nPCsPlZTyeEIW+rqEhbtUDSmFQ2Svn9WEMi3hEVQRJQnwFjQ+QQ3b+sNi1gakZ6P888nmwXc5mrjLdaLz5fElWvvBg+l9Y72qFJ5o+hBd1pCjBdqu316E9RfGS9bm2NM5sjURXlxiii0qyPXfDjqV8a/YqMlKT1RaRk7sIHr5xLNvKOWSF8sdgLCXKt8CnyKL4GGDEQOfX4+n6GuOqSBwDCBvaADAgERooG1BIGynj15ujU6Am05LR2WBLHin8hV0FuZycNyrhW3Ww8A4HeyruHYYKejOSMj8SabVW4jyugQtdeNhvrLeRJ6Ghinp0AGTFDrroYRouZuwtsNr9mMioubXJ/Yq1g04x8ZMnycNy7wwVama3xnaXGXKsTqit9J+ApLcltjMNE9WIzKaBo1UnTTF0F9KjM8OGIo4HShOjUWY5+8K5fWXfByYK1hZS7hS6EvS5GLQv/ypN6CTSRlpQ==
22:03:45  PROC: Jb readline: * call acceptSecContext
22:03:45  PROC: Jb readline: PROCISFUN:true
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOdBpvoXd9VSy3R9gt7RcIb+FEBmYa0pV3SqszQ0zI2GpdiI68Wa+QeAPNyp82aL+B//sQsSh15KVyuRuH3ipQmqg5ggG4w3srcaNZfORhddjtV0NRG9OwwQljnlOFETk6TWzwwhrlFAH3+ECnLfMogOCmYABLs7DwpqbWqWCY4n7vwuIbOecs4Dvg/vLWdfAtCQ9PA681329oBIX7z1pAjzxLwdk4xhaFEjlW3+9E3RBHQjqSBwDCBvaADAgERooG1BIGy0hMI6pv5JxtOPUXY1BfnSWSy7QqCgtlWHcWJcv3T/te5v/7PZ1xkk6aXal0XdBUd8SDJSCTADBdMmdVzh6gJet7Nj7wR9y88d0/41hdFvbcCb1WtHo1IKhFDHVp27SpXAdcjQe0UGQTPNggqyPMalg2e9CEG8qQU418MCB8DgZf9nKpsSsj+KiY1y6P4zMPfHDeknQuVtJuxfzaI2GgF8Qv/j5hH4nsuxwzh6vAGF/O4Vw==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623596864
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623596864, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623596864, pContext=0, pDelegCred=0
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=109
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Nb readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Nb readline: PROCISFUN:false
22:03:45  PROC: Ja println: TEST
22:03:45  PROC: Ja println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlNBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOnbSPmTFvLY9ys7O10OQIvG7PSHcBiAQmSKobNLx5gtdzpWO8vQFstVDm/FgwI8RltNjbeskPQKjZSqdkRKUmN+g3XyFzf2YsKCDQVClgKAvpkMISxR8PhBK0f2sW/DyaBCCqZZ/7Tv/GhcVJEKWPfnGc3FArqRf2gMPsWdK5gzROwZOc9aKfny3VPEXKAU952XtVntaF/qh6MTxthP9auUslCcKCy3Eij0x3hRLSmjCiD6KSBwDCBvaADAgERooG1BIGyXzRAgzHxTEiZt05XKbM8sUIQhiMuvs7+5zleNhh+YHcDCmCGz6/nY2bCtkdKCsvhXCQ7wml3//w/qPg2eZPlUb78sWqCdVcPbH+rSfEIAKQl4Y9nALIHk/WggDjf1TRLIJ1if2bFZ5HwKofyhP+pE6Q6XRMFWKUdqjXt8BuAOJRLkJLPpvrsLom0bZZY9PzvsEEFBSe/BfVfKRfNtMDZH3xi73uuqX4gTgf9Drz+FJTUCw==
22:03:45  PROC: Ja readline: * call acceptSecContext
22:03:45  PROC: Ja readline: PROCISFUN:true
22:03:45  PROC: Ja println: TEST
22:03:45  PROC: Ja println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMxsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbPwY9ZlUvA+ObWdJxj25xPxBe+QAFcbitc5lR/CqDF6juIJ0dblfErYTjWm0g+25XN4WM2rWu6UM2rgrKeDg/xqQ5/chfRJ3ksZJUFpErybdX9F52N2gadQDy6gP6MUEf4trRe+pibDjd4wNrpGi+/+opAKczCY9PAiFWIS6uQNP8NCKm5JDAq86G6UpSlj4F0DgkfgbLB1mRToyGYyUhck2xKf5ljHA7XpFM2LZQQt2WZxbaSBwDCBvaADAgERooG1BIGyfh//h8RKi2oVc4WC+wIylhM6FrIJXBa+H+c9mG8NQPeZXsXiVC/Ve0/HM1kgqYj289SVfia3DlrDlBZLY2ssmT3OOoQwS46Hb63EXSZV2WvlL7zgM2u/P+3H2B5FXEOJ1p7+dh157+FwzZ2JYwxJFGj4rZku8PFkGUvnGHzfx0EzcAJ6G9e5hJXrvKUgECOk37lQT1NVV2Wo1K+99BaEKhtnftnzWkV4UdwNJ657lP8gtg==
22:03:45  PROC: Ja readline: * call acceptSecContext
22:03:45  PROC: Ja readline: PROCISFUN:false
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbOlL+x6PagQN9wOuuQIzyTJ1h4d7AoDIgAhWaUEnGWFZcGdGO7XtHTLTX7efhE/ffUHTuze2j0cUYhBNzKCTlo/Sk/YGmMfXAiK+Is/+ro2lL6Dn+q4U6f2qCCTzmnzBin2VD7KQNPfYwfF36C2qGw4uf8Il/fhktEMu4lJDANcNT2VJH/bb6J9AvEBGI9TRRe4grw4bvVul5gXMjx1b4HcraUsBXQLJWVzW4/wA0tsyMGuR6SBwDCBvaADAgERooG1BIGyNWkHrSB0E0rRkrN4NEHT3ugGFAnTLkD1T9Ntep3EkhdOBhjo0qJgZCvBEOvhyzmjsjx6xr0ixxeNvtTFNQk/7aLvDE6/+8uAnO+0DxBXJiu5RHnomzVaitgTt4Wj3GR8JZJ0k8RO1UQ8HehMSl+FOPorR0f2fSMBILeg5QDU24L9/orXQtJc+ahdM4V8QCul9XNmWEEzs25135JH4nz/k7k/DC/xYzCsp9UY8uF6VoSLjg==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623432512
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623432512, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623432512, pContext=140109623398400, pDelegCred=0
22:03:45  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=140109623398400
22:03:45  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623596480
22:03:45  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=140109623596480
22:03:45  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 140109623398400, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=140109623595296
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623595296
22:03:45  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140109623432512
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=140109623595296, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623432208
22:03:45  PROC: Nb readline: PROCISFUN:true
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMhsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbN8I+pwXOAxRVBDOEgM/jRjOlQ+mXpNxNl7T3JEBCSoA9ZvMcPuKsSJeN55zBmeWhQ5bp9AOrwausb7oe56oYXHc5ENORZ0oZTYw+moWEbBRa1l9KgSGGAb+4reM/Z+kV5U0qiaXk73AEkYPMi3KkeOjadhvF5Y3rX25LlnBxYxVud/m2xqp1pF19hZlymDqhU7ehGM4ZxcuTOKXY7aFCHCU5i5a1uD/Ji7pJBKGjddDwNtHqSBwDCBvaADAgERooG1BIGyPih8mDeM5wvLEbonpKHIUArj8UfOUz0LaeaCp7J5niBM2W9SX09BZT6IVRd6JqsNTBfi2v1fftsCpiv2fZNPX0vgTitgzehoannxZloENDGv59dHpk3FGRhfmw9nSVhsdL81yY95lAgRfj06hYKe85IgTkcn7993QeYtZrcRdpFjocMR7qx6oRjg83g5ZpZ3tETSJd3ooopFQGVlWniijTw9MJ4jT1XI48Iowjixf9B8dQ==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623432512
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623432512, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623432512, pContext=140109623437200, pDelegCred=0
22:03:45  PROC: Nb readline: [GSSLibStub:1062] [GSSLibStub_acceptContext] set pContext=140109623437200
22:03:45  PROC: Nb readline: [GSSLibStub:1072] [GSSLibStub_acceptContext] set flags=0x1be
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623437568
22:03:45  PROC: Nb readline: [GSSLibStub:1108] [GSSLibStub_acceptContext] set srcName=140109623437568
22:03:45  PROC: Nb readline: [GSSLibStub:1117] [GSSLibStub_acceptContext] context established
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=138
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 140109623437200, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:1299] [GSSLibStub_getContextName] pName=140109623637824
22:03:45  PROC: Nb readline: [GSSLibStub:569] [GSSLibStub_displayName] 140109623637824
22:03:45  PROC: Nb readline: [GSSLibStub:674] [GSSLibStub_releaseCred] 140109623432512
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=140109623637824, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623592928
22:03:45  PROC: Nb readline: PROCISFUN:true
22:03:45  PROC: Nb println: TEST
22:03:45  PROC: Nb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbO6sJJI4Ofzd+gRdrqyov9LyCfujSaqIAi7AemTS/kbKA1bQJ3CpxPOPzTN9iiRF4GRo+wvQmaCk6OcceKP/HHG8OrL5ZkXdvVKgp6hl5CyRPirhgyjCxkNCQdh3RAr7KIc0iQ1sWlzhfAe2Moty5pcQpsd4KCft6tAdG78kBqDORBCn/thFcsyr88OUBZtDGIFp8Xf+hYdkjaTwwm22d7GWWpFEVPB5nWtk85/xC09LUYHDqSBwDCBvaADAgERooG1BIGybl3eFJMX8fq7b4KuOAdyDGEuffxXH+hKqnzgHjdmsl3zogYm3OEDQI/kInCD9KQTt1JskWzcZ74+Rn/NpBRXSoCMENIIzxGcCjVkPicLIhHVw3GLbfygKRIoAP1m28pTW7lmfi2OhUo8n1Q9YY/npF43TplObUQ9u1KSuCbV8UIsbLOE1ZQNpCGCgGvgHcYVMUP7dtfXD+4SrBe692FV5KLm4Nat+XlPALNaW56kitUusg==
22:03:45  PROC: Nb readline: [GSSLibStub:633] [GSSLibStub_acquireCred]
22:03:45  PROC: Nb readline: [GSSLibStub:640] [GSSLibStub_acquireCred] pName=0, usage=2
22:03:45  PROC: Nb readline: [GSSLibStub:650] [GSSLibStub_acquireCred] pCred=140109623432512
22:03:45  PROC: Nb readline: * call acceptSecContext
22:03:45  PROC: Nb readline: SunNativeGSS: Precomputed mechToken length: 484
22:03:45  PROC: Nb readline: SunNativeGSS: Complete Token length: 499
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> inToken len=499
22:03:45  PROC: Nb readline: [GSSLibStub:1017] [GSSLibStub_acceptContext]
22:03:45  PROC: Nb readline: [GSSLibStub:1037] [GSSLibStub_acceptContext] before: pCred=140109623432512, pContext=0
22:03:45  PROC: Nb readline: [GSSLibStub:1054] [GSSLibStub_acceptContext] after: pCred=140109623432512, pContext=0, pDelegCred=0
22:03:45  PROC: Nb readline: SunNativeGSS: acceptSecContext=> outToken len=109
22:03:45  PROC: Nb readline: [GSSLibStub:1282] [GSSLibStub_getContextName] 0, isSrc=0
22:03:45  PROC: Nb readline: [GSSLibStub:535] [GSSLibStub_inquireContextAll] Status major/minor = 1080000/0
22:03:45  PROC: Nb readline: [GSSLibStub:537] c/r/s = 1/8/0 
22:03:45  PROC: Nb readline: PROCISFUN:false
22:03:45  PROC: Jb println: TEST
22:03:45  PROC: Jb println: YIIB7wYJKoZIhvcSAQICAQBuggHeMIIB2qADAgEFoQMCAQ6iBwMFACAAAACjggEAYYH9MIH6oAMCAQWhDRsLUkFCQklULkhPTEWiIDAeoAMCAQChFzAVGwhzZXJ2aWNlMBsJbG9jYWxob3N0o4HBMIG+oAMCARGigbYEgbNHeOAnl1rLpd550l39ivhFmJhjeX2l6Upi93N6fYL1GI9brMdQH9Agw2w/Piw3Vh6W+fSbvC15F9oAFqtdU94Tt0wP913hXMHPGIhqx+GGGbOO77geizob0IOYdaBNFtj13vnQ/6QTELVDkxTI8WsYB5VFPxqcMgCYOsQ7xPtggw4nXQHieU8US/ogM7+GQOuH0S/0tbTcjrfITYmRQYCRuKIG5KRnY0haY/EW7fwn5mrqbKSBwDCBvaADAgERooG1BIGyL3/Rs5IDPrt8e9DBaPtd9dAACKe8WueaQWM35niqRblzARN/tI4losZCB9Jzc0BLv/McmC+gZcdxrpPVWclExxp+SZbo6TEKjL4z8KM60X3Crh/Yyi+LovrU8aRXNFM07f5Cu1feze64hiEW7cTS8PqcDkkqGJuXtxY0aiIijcP1jZTiwuTukrquuJTtJf+cED5xZLwQJyzQGgoiRo1SbV/PvO+sg8x49f8bJx8hxKUy/w==
22:03:45  PROC: Jb readline: * call acceptSecContext
22:03:45  PROC: Jb readline: PROCISFUN:true
22:03:45  PROC: C println: END
22:03:45  PROC: Ja println: END
22:03:45  PROC: Jb println: END
22:03:45  PROC: Na println: END
22:03:45  PROC: Nb println: END
22:03:45  
22:03:45  All Test Results
22:03:45  ================
22:03:45          req**  client    service  acceptor   Result
22:03:45  ----  -------  ------  ---------  --------  -------
22:03:45    0:      0**      c3    s0  (0)        Na   ++     
22:03:45    1:      1**      c2    s0  (2)        Ja   ++     
22:03:45    2:      1        c2    s0  (2)        Na   ++  xxx
22:03:45    3:      2**      c4    s2  (0)        Nb   ++     
22:03:45    4:      2        c4    s2  (0)        Nb   --     
22:03:45    5:      1        c2    s0  (2)        Na   --     
22:03:45    6:      2        c4    s2  (0)        Nb   --     
22:03:45    7:      3**      c3    s1  (0)        Nb   ++     
22:03:45    8:      3        c3    s1  (2)        Ja   ++  xxx
22:03:45    9:      3        c3    s1  (2)        Na   --     
22:03:45   10:      3        c3    s1  (2)        Na   --     
22:03:45   11:      4**      c4    s0  (2)        Nb   ++     
22:03:45   12:      5**      c3    s1  (2)        Na   ++     
22:03:45   13:      1        c2    s0  (2)        Ja   --     
22:03:45   14:      2        c4    s2  (2)        Jb   ++  xxx
22:03:45   15:      6**      c3    s1  (2)        Na   ++     
22:03:45   16:      3        c3    s1  (2)        Nb   --     
22:03:45   17:      7**      c1    s0  (2)        Nb   ++     
22:03:45   18:      8**      c4    s3  (0)        Na   ++     
22:03:45   19:      4        c4    s0  (2)        Na   --     
22:03:45   20:      7        c1    s0  (4)        Jb   ++  xxx
22:03:45   21:      9**      c2    s3  (0)        Na   ++     
22:03:45   22:     10**      c4    s3  (0)        Na   ++     
22:03:45   23:      6        c3    s1  (2)        Nb   --     
22:03:45   24:      9        c2    s3  (2)        Jb   ++  xxx
22:03:45   25:      3        c3    s1  (2)        Nb   --     
22:03:45   26:     10        c4    s3  (4)        Jb   ++  xxx
22:03:45   27:     11**      c0    s4  (2)        Jb   ++     
22:03:45   28:     12**      c2    s1  (2)        Nb   ++     
22:03:45   29:     13**      c4    s0  (6)        Jb   ++     
22:03:45   30:      4        c4    s0  (8)        Ja   ++  xxx
22:03:45   31:     14**      c1    s2  (4)        Ja   ++     
22:03:45   32:     15**      c1    s1  (4)        Ja   ++     
22:03:45   33:      3        c3    s1  (4)        Na   --     
22:03:45   34:     10        c4    s3  (4)        Na   --     
22:03:45   35:     14        c1    s2  (4)        Ja   --     
22:03:45   36:     13        c4    s0  (8)        Na   ++  xxx
22:03:45   37:      1        c2    s0  (8)        Ja   --     
22:03:45   38:     16**      c4    s1  (4)        Na   ++     
22:03:45   39:     17**      c1    s2  (4)        Nb   ++     
22:03:45   40:     18**      c3    s3  (4)        Nb   ++     
22:03:45   41:     19**      c2    s3  (4)        Nb   ++     
22:03:45   42:     20**      c2    s4  (4)        Ja   ++     
22:03:45   43:     21**      c0    s1  (6)        Jb   ++     
22:03:45   44:     22**      c0    s0 (10)        Jb   ++     
22:03:45   45:      0        c3    s0 (12)        Ja   ++  xxx
22:03:45   46:      1        c2    s0 (12)        Nb   --     
22:03:45   47:     23**      c2    s4  (6)        Ja   ++     
22:03:45   48:     14        c1    s2  (4)        Ja   --     
22:03:45   49:     11        c0    s4  (6)        Nb   ++  xxx
22:03:45   50:     24**      c4    s1  (6)        Na   ++     
22:03:45   51:      2        c4    s2  (4)        Jb   --     
22:03:45   52:     25**      c2    s2  (6)        Jb   ++     
22:03:45   53:     26**      c2    s2  (8)        Jb   ++     
22:03:45   54:     12        c2    s1  (8)        Jb   ++  xxx
22:03:45   55:     25        c2    s2  (8)        Jb   --     
22:03:45   56:     11        c0    s4  (6)        Jb   --     
22:03:45   57:     27**      c1    s3  (4)        Nb   ++     
22:03:45   58:     20        c2    s4  (6)        Jb   --     
22:03:45   59:     28**      c1    s2  (8)        Nb   ++     
22:03:45   60:     29**      c3    s0 (12)        Nb   ++     
22:03:45   61:     30**      c3    s0 (12)        Nb   ++     
22:03:45   62:     31**      c2    s3  (4)        Nb   ++     
22:03:45   63:     32**      c4    s3  (4)        Nb   ++     
22:03:45   64:     11        c0    s4  (6)        Ja   --     
22:03:45   65:      2        c4    s2  (8)        Nb   --     
22:03:45   66:     19        c2    s3  (4)        Na   --     
22:03:45   67:      3        c3    s1  (8)        Ja   --     
22:03:45   68:     33**      c4    s0 (14)        Ja   ++     
22:03:45   69:      4        c4    s0 (14)        Na   --     
22:03:45   70:     34**      c1    s1  (8)        Nb   ++     
22:03:45   71:      1        c2    s0 (14)        Ja   --     
22:03:45   72:     35**      c1    s0 (14)        Nb   ++     
22:03:45   73:     32        c4    s3  (4)        Na   --     
22:03:45   74:     16        c4    s1  (8)        Na   --     
22:03:45   75:     36**      c1    s4  (6)        Na   ++     
22:03:45   76:     37**      c4    s1 (10)        Ja   ++     
22:03:45   77:     34        c1    s1 (12)        Ja   ++  xxx
22:03:45   78:     28        c1    s2 (10)        Jb   ++  xxx
22:03:45   79:     29        c3    s0 (14)        Nb   --     
22:03:45   80:     38**      c3    s3  (4)        Nb   ++     
22:03:45   81:     39**      c0    s3  (6)        Ja   ++     
22:03:45   82:     40**      c4    s4  (6)        Nb   ++     
22:03:45   83:     32        c4    s3  (6)        Na   --     
22:03:45   84:     41**      c4    s2 (10)        Na   ++     
22:03:45   85:     42**      c4    s2 (12)        Ja   ++     
22:03:45   86:     18        c3    s3  (6)        Nb   --     
22:03:45   87:     43**      c1    s3  (8)        Jb   ++     
22:03:45   88:     31        c2    s3 (10)        Jb   ++  xxx
22:03:45   89:      7        c1    s0 (14)        Na   --     
22:03:45   90:     44**      c4    s1 (12)        Nb   ++     
22:03:45   91:     35        c1    s0 (14)        Nb   --     
22:03:45   92:     44        c4    s1 (14)        Jb   ++  xxx
22:03:45   93:     28        c1    s2 (12)        Nb   --     
22:03:45   94:     45**      c3    s4  (8)        Ja   ++     
22:03:45   95:     43        c1    s3 (10)        Ja   --     
22:03:45   96:     46**      c0    s2 (12)        Nb   ++     
22:03:45   97:     26        c2    s2 (12)        Nb   ++  xxx
22:03:45   98:      7        c1    s0 (14)        Nb   --     
22:03:45   99:     47**      c0    s0 (16)        Jb   ++     
22:03:45  
22:03:45  Path of Reqs
22:03:45  ============
22:03:45    0 (c3 -> s0): Na(0) -> Ja(45)xxx
22:03:45    1 (c2 -> s0): Ja(1) -> Na(2)xxx -> Na(5) -> Ja(13) -> Ja(37) -> Nb(46) -> Ja(71)
22:03:45    2 (c4 -> s2): Nb(3) -> Nb(4) -> Nb(6) -> Jb(14)xxx -> Jb(51) -> Nb(65)
22:03:45    3 (c3 -> s1): Nb(7) -> Ja(8)xxx -> Na(9) -> Na(10) -> Nb(16) -> Nb(25) -> Na(33) -> Ja(67)
22:03:45    4 (c4 -> s0): Nb(11) -> Na(19) -> Ja(30)xxx -> Na(69)
22:03:45    5 (c3 -> s1): Na(12)
22:03:45    6 (c3 -> s1): Na(15) -> Nb(23)
22:03:45    7 (c1 -> s0): Nb(17) -> Jb(20)xxx -> Na(89) -> Nb(98)
22:03:45    8 (c4 -> s3): Na(18)
22:03:45    9 (c2 -> s3): Na(21) -> Jb(24)xxx
22:03:45   10 (c4 -> s3): Na(22) -> Jb(26)xxx -> Na(34)
22:03:45   11 (c0 -> s4): Jb(27) -> Nb(49)xxx -> Jb(56) -> Ja(64)
22:03:45   12 (c2 -> s1): Nb(28) -> Jb(54)xxx
22:03:45   13 (c4 -> s0): Jb(29) -> Na(36)xxx
22:03:45   14 (c1 -> s2): Ja(31) -> Ja(35) -> Ja(48)
22:03:45   15 (c1 -> s1): Ja(32)
22:03:45   16 (c4 -> s1): Na(38) -> Na(74)
22:03:45   17 (c1 -> s2): Nb(39)
22:03:45   18 (c3 -> s3): Nb(40) -> Nb(86)
22:03:45   19 (c2 -> s3): Nb(41) -> Na(66)
22:03:45   20 (c2 -> s4): Ja(42) -> Jb(58)
22:03:45   21 (c0 -> s1): Jb(43)
22:03:45   22 (c0 -> s0): Jb(44)
22:03:45   23 (c2 -> s4): Ja(47)
22:03:45   24 (c4 -> s1): Na(50)
22:03:45   25 (c2 -> s2): Jb(52) -> Jb(55)
22:03:45   26 (c2 -> s2): Jb(53) -> Nb(97)xxx
22:03:45   27 (c1 -> s3): Nb(57)
22:03:45   28 (c1 -> s2): Nb(59) -> Jb(78)xxx -> Nb(93)
22:03:45   29 (c3 -> s0): Nb(60) -> Nb(79)
22:03:45   30 (c3 -> s0): Nb(61)
22:03:45   31 (c2 -> s3): Nb(62) -> Jb(88)xxx
22:03:45   32 (c4 -> s3): Nb(63) -> Na(73) -> Na(83)
22:03:45   33 (c4 -> s0): Ja(68)
22:03:45   34 (c1 -> s1): Nb(70) -> Ja(77)xxx
22:03:45   35 (c1 -> s0): Nb(72) -> Nb(91)
22:03:45   36 (c1 -> s4): Na(75)
22:03:45   37 (c4 -> s1): Ja(76)
22:03:45   38 (c3 -> s3): Nb(80)
22:03:45   39 (c0 -> s3): Ja(81)
22:03:45   40 (c4 -> s4): Nb(82)
22:03:45   41 (c4 -> s2): Na(84)
22:03:45   42 (c4 -> s2): Ja(85)
22:03:45   43 (c1 -> s3): Jb(87) -> Ja(95)
22:03:45   44 (c4 -> s1): Nb(90) -> Jb(92)xxx
22:03:45   45 (c3 -> s4): Ja(94)
22:03:45   46 (c0 -> s2): Nb(96)
22:03:45   47 (c0 -> s0): Jb(99)
22:03:45  
22:03:45  STDERR:
22:03:45  Nsanity started
22:03:45  Ja started
22:03:45  Jb started
22:03:45  Na started
22:03:45  Nb started
22:03:45  java.lang.Exception
22:03:45  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:286)
22:03:45  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:333)
22:03:45  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
22:03:45  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
22:03:45  	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
22:03:45  	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
22:03:45  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
22:03:45  	at java.base/java.lang.Thread.run(Thread.java:836)
22:03:45  java.lang.Exception
22:03:45  	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:286)
22:03:45  	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:333)
22:03:45  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
22:03:45  	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
22:03:45  	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
22:03:45  	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
22:03:45  	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
22:03:45  	at java.base/java.lang.Thread.run(Thread.java:836)
22:03:45  
22:03:45  JavaTest Message: Test threw exception: java.lang.Exception
22:03:45  JavaTest Message: shutting down test
22:03:45  
22:03:45  STATUS:Failed.`main' threw exception: java.lang.Exception

@JasonFengJ9
Copy link
Member

A similar failure is observed at an internal JDK8 aarch64_linux build Test_openjdk8_j9_extended.openjdk_aarch64_linux/5/:

[2021-05-01T06:14:12.497Z] Running test jdk_security4_0 ...
[2021-05-01T06:14:12.497Z] ===============================================
[2021-05-01T06:14:12.497Z] jdk_security4_0 Start Time: Sat May  1 02:14:11 2021 Epoch Time (ms): 1619849651430
[2021-05-01T06:14:12.497Z] "/home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_aarch64_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:destroyAll; "/home/jenkins/workspace/Test_openjdk8_j9_extended.openjdk_aarch64_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:groupAccess,destroyAll; echo "cache cleanup done";
[2021-05-01T06:14:12.497Z] JVMSHRC005I No shared class caches available
[2021-05-01T06:14:12.497Z] JVMSHRC005I No shared class caches available
[2021-05-01T06:14:12.497Z] cache cleanup done
[2021-05-01T06:14:12.497Z] variation: Mode150
[2021-05-01T06:14:12.497Z] JVM_OPTIONS:  -XX:+UseCompressedOops 

[2021-05-01T06:15:21.270Z] --------------------------------------------------
[2021-05-01T06:15:21.271Z] TEST: sun/security/krb5/auto/ReplayCacheTestProc.java

[2021-05-01T06:15:21.311Z] Console sees true
[2021-05-01T06:15:21.311Z] java.lang.Exception
[2021-05-01T06:15:21.311Z] 	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:174)
[2021-05-01T06:15:21.311Z] 	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:217)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2021-05-01T06:15:21.311Z] 	at java.lang.reflect.Method.invoke(Method.java:498)
[2021-05-01T06:15:21.311Z] 	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
[2021-05-01T06:15:21.311Z] 	at java.lang.Thread.run(Thread.java:821)
[2021-05-01T06:15:21.311Z] java.lang.Exception
[2021-05-01T06:15:21.311Z] 	at ReplayCacheTestProc.main0(ReplayCacheTestProc.java:174)
[2021-05-01T06:15:21.311Z] 	at ReplayCacheTestProc.main(ReplayCacheTestProc.java:217)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2021-05-01T06:15:21.311Z] 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2021-05-01T06:15:21.311Z] 	at java.lang.reflect.Method.invoke(Method.java:498)
[2021-05-01T06:15:21.311Z] 	at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
[2021-05-01T06:15:21.311Z] 	at java.lang.Thread.run(Thread.java:821)
[2021-05-01T06:15:21.311Z] 
[2021-05-01T06:15:21.311Z] JavaTest Message: Test threw exception: java.lang.Exception
[2021-05-01T06:15:21.311Z] JavaTest Message: shutting down test
[2021-05-01T06:15:21.311Z] 
[2021-05-01T06:15:21.311Z] STATUS:Failed.`main' threw exception: java.lang.Exception

@pshipton
Copy link
Member

Closing as a dup of #13756

@pshipton pshipton mentioned this issue Jul 20, 2022
Open
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
test failure
Projects
None yet
Milestone
JTReg Backlog
Development

No branches or pull requests
4 participants
@JasonFengJ9 @pshipton @lumpfish @andrew-m-leonard