./gradlew :x-pack:qa:third-party:active-directory:test -Dtests.class=org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT -Dtests.method="testCanAuthenticateAgainstBothRealms" -Dtests.security.manager=true -Dtests.locale=en-IE -Dtests.timezone=Asia/Amman -Dcompiler.java=10 -Druntime.java=8
> Task :x-pack:qa:third-party:active-directory:compileTestJava
Note: Some input files use or override a deprecated API.
Note: Recompile with -Xlint:deprecation for details.
Note: /home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/src/test/java/org/elasticsearch/xpack/security/authc/ldap/ADLdapUserSearchSessionFactoryTests.java uses unchecked or unsafe operations.
Note: Recompile with -Xlint:unchecked for details.
Suite: org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT
1> [2018-09-05T08:43:37,777][WARN ][o.e.b.JNANatives ] [[SUITE-MultipleAdRealmIT-seed#[D87F1070EEC3E5AF]]] Unable to lock JVM Memory: error=12, reason=Cannot allocate memory
1> [2018-09-05T08:43:37,780][WARN ][o.e.b.JNANatives ] [[SUITE-MultipleAdRealmIT-seed#[D87F1070EEC3E5AF]]] This can result in part of the JVM being swapped out.
1> [2018-09-05T08:43:37,780][WARN ][o.e.b.JNANatives ] [[SUITE-MultipleAdRealmIT-seed#[D87F1070EEC3E5AF]]] Increase RLIMIT_MEMLOCK, soft limit: 16777216, hard limit: 16777216
1> [2018-09-05T08:43:37,781][WARN ][o.e.b.JNANatives ] [[SUITE-MultipleAdRealmIT-seed#[D87F1070EEC3E5AF]]] These can be adjusted by modifying /etc/security/limits.conf, for example:
1> # allow user 'jake' mlockall
1> jake soft memlock unlimited
1> jake hard memlock unlimited
1> [2018-09-05T08:43:37,781][WARN ][o.e.b.JNANatives ] [[SUITE-MultipleAdRealmIT-seed#[D87F1070EEC3E5AF]]] If you are logged in interactively, you will have to re-login for the new limits to take effect.
1> [2018-09-05T16:43:38,336][INFO ][test ] [suite] running test with realm configuration [AD], with direct group to role mapping [false]. Settings [{"xpack.security.authc.realms.external.domain_name":"ad.test.elasticsearch.com","xpack.security.authc.realms.external.follow_referrals":"false","xpack.security.authc.realms.external.group_search.base_dn":"CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com","xpack.security.authc.realms.external.group_search.scope":"ONE_LEVEL","xpack.security.authc.realms.external.port.gc_ldap":"63268","xpack.security.authc.realms.external.port.gc_ldaps":"63269","xpack.security.authc.realms.external.port.ldap":"61389","xpack.security.authc.realms.external.port.ldaps":"61636","xpack.security.authc.realms.external.type":"active_directory","xpack.security.authc.realms.external.url":"ldaps://localhost:61636"}]
1> [2018-09-05T16:43:38,337][INFO ][test ] [suite] running test with secondary realm configuration [AD_LDAP_GROUPS_FROM_SEARCH], with direct group to role mapping [false]. Settings [{"xpack.security.authc.realms.external.follow_referrals":"false","xpack.security.authc.realms.external.group_search.base_dn":"CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com","xpack.security.authc.realms.external.group_search.scope":"ONE_LEVEL","xpack.security.authc.realms.external.type":"ldap","xpack.security.authc.realms.external.url":"ldaps://localhost:61636","xpack.security.authc.realms.external.user_dn_templates":["cn={0},CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com"]}]
1> [2018-09-05T16:43:38,374][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] before test
1> [2018-09-05T16:43:38,374][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] [MultipleAdRealmIT#testCanAuthenticateAgainstBothRealms]: setting up test
1> [2018-09-05T16:43:38,379][INFO ][o.e.t.InternalTestCluster] [testCanAuthenticateAgainstBothRealms] Setup InternalTestCluster [SUITE-CHILD_VM=[0]-CLUSTER_SEED=[7302039248862991509]-HASH=[4D0F6CAD8599]-cluster] with seed [65560E024C705495] using [0] dedicated masters, [2] (data) nodes and [1] coord only nodes (min_master_nodes are [auto-managed])
> Task :x-pack:qa:third-party:active-directory:test
[junit4] <JUnit4> says 你好! Master seed: D87F1070EEC3E5AF
==> Test Info: seed=D87F1070EEC3E5AF; jvm=1; suite=1
1> [2018-09-05T16:43:38,606][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] using [1] data paths, mounts [[/home (/dev/nvme1n1p1)]], net usable_space [338.5gb], net total_space [476.7gb], types [xfs]
1> [2018-09-05T16:43:38,606][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] heap size [491mb], compressed ordinary object pointers [true]
1> [2018-09-05T16:43:38,607][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] node name [node_s0], node ID [TU-sRefuQXeteZQHG1tdnA]
1> [2018-09-05T16:43:38,607][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version[7.0.0-alpha1-SNAPSHOT], pid[29493], build[unknown/unknown/74b8798/2018-09-05T13:43:21.523623Z], OS[Linux/4.17.4-200.fc28.x86_64/amd64], JVM[Oracle Corporation/Java HotSpot(TM) 64-Bit Server VM/1.8.0_171/25.171-b11]
1> [2018-09-05T16:43:38,607][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] JVM arguments [-ea, -esa, -Xmx512m, -Xms512m, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/heapdump, -Dtests.prefix=tests, -Dtests.seed=D87F1070EEC3E5AF, -Dtests.security.manager=true, -Djava.io.tmpdir=./temp, -Dtests.gradle=true, -Des.set.netty.runtime.available.processors=false, -Des.scripting.use_java_time=true, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Dcompiler.java=10, -Dtests.task=:x-pack:qa:third-party:active-directory:test, -Dtests.timezone=Asia/Amman, -Djna.nosys=true, -Druntime.java=8, -Des.scripting.update.ctx_in_params=false, -Dtests.locale=en-IE, -Dtests.artifact=active-directory, -Djava.awt.headless=true, -Dtests.class=org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT, -Dtests.logger.level=WARN, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Djunit4.childvm.cwd=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0, -Djunit4.childvm.id=0, -Djunit4.childvm.count=1]
1> [2018-09-05T16:43:38,608][WARN ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version [7.0.0-alpha1-SNAPSHOT] is a pre-release version of Elasticsearch and is not suitable for production
1> [2018-09-05T16:43:38,639][WARN ][o.e.d.c.s.Settings ] [testCanAuthenticateAgainstBothRealms] [key_passphrase] setting was deprecated in Elasticsearch and will be removed in a future release! See the breaking changes documentation for the next major version.
1> [2018-09-05T16:43:38,803][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:38,804][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:38,804][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:38,804][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.node.NodeMocksPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.search.MockSearchService$TestPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$AssertActionNamePlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$TestSeedPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.InternalSettingsPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.MockHttpTransport$TestPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.discovery.TestZenDiscovery$TestPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.store.MockFSIndexStore$TestPlugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:38,805][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.security.LocalStateSecurity]
1> [2018-09-05T16:43:38,881][INFO ][i.n.u.i.PlatformDependent] [testCanAuthenticateAgainstBothRealms] Your platform does not provide complete low-level API for accessing direct buffers reliably. Unless explicitly requested, heap buffer will always be preferred to avoid potential system instability.
1> [2018-09-05T16:43:39,575][WARN ][o.e.d.c.s.Settings ] [testCanAuthenticateAgainstBothRealms] [xpack.ssl.key_passphrase] setting was deprecated in Elasticsearch and will be removed in a future release! See the breaking changes documentation for the next major version.
1> [2018-09-05T16:43:40,604][WARN ][o.e.d.x.s.a.l.ActiveDirectorySessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external.ssl.verification_mode] instead
1> [2018-09-05T16:43:40,666][WARN ][o.e.d.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external2.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external2.ssl.verification_mode] instead
1> [2018-09-05T16:43:40,666][INFO ][o.e.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] Realm [external2] is in user-dn-template mode: [[cn={0},CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com]]
1> [2018-09-05T16:43:40,729][INFO ][o.e.x.s.a.s.FileRolesStore] [testCanAuthenticateAgainstBothRealms] parsed [5] roles from file [/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0/temp/org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT_D87F1070EEC3E5AF-001/tempDir-003/SUITE-0/config/roles.yml]
1> [2018-09-05T16:43:40,916][INFO ][o.e.d.DiscoveryModule ] [testCanAuthenticateAgainstBothRealms] using discovery type [test-zen]
1> [2018-09-05T16:43:41,351][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] initialized
1> [2018-09-05T16:43:41,364][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] using [1] data paths, mounts [[/home (/dev/nvme1n1p1)]], net usable_space [338.5gb], net total_space [476.7gb], types [xfs]
1> [2018-09-05T16:43:41,364][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] heap size [491mb], compressed ordinary object pointers [true]
1> [2018-09-05T16:43:41,364][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] node name [node_s1], node ID [TdSvhFb-Q5mf1ic6UGPIiA]
1> [2018-09-05T16:43:41,364][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version[7.0.0-alpha1-SNAPSHOT], pid[29493], build[unknown/unknown/74b8798/2018-09-05T13:43:21.523623Z], OS[Linux/4.17.4-200.fc28.x86_64/amd64], JVM[Oracle Corporation/Java HotSpot(TM) 64-Bit Server VM/1.8.0_171/25.171-b11]
1> [2018-09-05T16:43:41,365][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] JVM arguments [-ea, -esa, -Xmx512m, -Xms512m, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/heapdump, -Dtests.prefix=tests, -Dtests.seed=D87F1070EEC3E5AF, -Dtests.security.manager=true, -Djava.io.tmpdir=./temp, -Dtests.gradle=true, -Des.set.netty.runtime.available.processors=false, -Des.scripting.use_java_time=true, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Dcompiler.java=10, -Dtests.task=:x-pack:qa:third-party:active-directory:test, -Dtests.timezone=Asia/Amman, -Djna.nosys=true, -Druntime.java=8, -Des.scripting.update.ctx_in_params=false, -Dtests.locale=en-IE, -Dtests.artifact=active-directory, -Djava.awt.headless=true, -Dtests.class=org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT, -Dtests.logger.level=WARN, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Djunit4.childvm.cwd=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0, -Djunit4.childvm.id=0, -Djunit4.childvm.count=1]
1> [2018-09-05T16:43:41,365][WARN ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version [7.0.0-alpha1-SNAPSHOT] is a pre-release version of Elasticsearch and is not suitable for production
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.node.NodeMocksPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.search.MockSearchService$TestPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$AssertActionNamePlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$TestSeedPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.InternalSettingsPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.MockHttpTransport$TestPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.discovery.TestZenDiscovery$TestPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.store.MockFSIndexStore$TestPlugin]
1> [2018-09-05T16:43:41,385][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:41,386][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.security.LocalStateSecurity]
1> [2018-09-05T16:43:41,844][WARN ][o.e.d.x.s.a.l.ActiveDirectorySessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external.ssl.verification_mode] instead
1> [2018-09-05T16:43:41,848][WARN ][o.e.d.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external2.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external2.ssl.verification_mode] instead
1> [2018-09-05T16:43:41,848][INFO ][o.e.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] Realm [external2] is in user-dn-template mode: [[cn={0},CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com]]
1> [2018-09-05T16:43:41,852][INFO ][o.e.x.s.a.s.FileRolesStore] [testCanAuthenticateAgainstBothRealms] parsed [5] roles from file [/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0/temp/org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT_D87F1070EEC3E5AF-001/tempDir-003/SUITE-1/config/roles.yml]
1> [2018-09-05T16:43:41,863][INFO ][o.e.d.DiscoveryModule ] [testCanAuthenticateAgainstBothRealms] using discovery type [test-zen]
1> [2018-09-05T16:43:41,888][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] initialized
1> [2018-09-05T16:43:41,900][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] using [1] data paths, mounts [[/home (/dev/nvme1n1p1)]], net usable_space [338.5gb], net total_space [476.7gb], types [xfs]
1> [2018-09-05T16:43:41,900][INFO ][o.e.e.NodeEnvironment ] [testCanAuthenticateAgainstBothRealms] heap size [491mb], compressed ordinary object pointers [true]
1> [2018-09-05T16:43:41,900][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] node name [node_sc2], node ID [PUaiK3X-Tdiht0sfCxziFA]
1> [2018-09-05T16:43:41,900][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version[7.0.0-alpha1-SNAPSHOT], pid[29493], build[unknown/unknown/74b8798/2018-09-05T13:43:21.523623Z], OS[Linux/4.17.4-200.fc28.x86_64/amd64], JVM[Oracle Corporation/Java HotSpot(TM) 64-Bit Server VM/1.8.0_171/25.171-b11]
1> [2018-09-05T16:43:41,901][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] JVM arguments [-ea, -esa, -Xmx512m, -Xms512m, -XX:+HeapDumpOnOutOfMemoryError, -XX:HeapDumpPath=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/heapdump, -Dtests.prefix=tests, -Dtests.seed=D87F1070EEC3E5AF, -Dtests.security.manager=true, -Djava.io.tmpdir=./temp, -Dtests.gradle=true, -Des.set.netty.runtime.available.processors=false, -Des.scripting.use_java_time=true, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Dcompiler.java=10, -Dtests.task=:x-pack:qa:third-party:active-directory:test, -Dtests.timezone=Asia/Amman, -Djna.nosys=true, -Druntime.java=8, -Des.scripting.update.ctx_in_params=false, -Dtests.locale=en-IE, -Dtests.artifact=active-directory, -Djava.awt.headless=true, -Dtests.class=org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT, -Dtests.logger.level=WARN, -Dtests.method=testCanAuthenticateAgainstBothRealms, -Djunit4.childvm.cwd=/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0, -Djunit4.childvm.id=0, -Djunit4.childvm.count=1]
1> [2018-09-05T16:43:41,901][WARN ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] version [7.0.0-alpha1-SNAPSHOT] is a pre-release version of Elasticsearch and is not suitable for production
1> [2018-09-05T16:43:41,920][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.node.NodeMocksPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.search.MockSearchService$TestPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$AssertActionNamePlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.ESIntegTestCase$TestSeedPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.InternalSettingsPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.MockHttpTransport$TestPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.discovery.TestZenDiscovery$TestPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.test.store.MockFSIndexStore$TestPlugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:41,921][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.security.LocalStateSecurity]
1> [2018-09-05T16:43:42,360][WARN ][o.e.d.x.s.a.l.ActiveDirectorySessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external.ssl.verification_mode] instead
1> [2018-09-05T16:43:42,363][WARN ][o.e.d.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] the setting [xpack.security.authc.realms.external2.hostname_verification] has been deprecated and will be removed in a future version. use [xpack.security.authc.realms.external2.ssl.verification_mode] instead
1> [2018-09-05T16:43:42,363][INFO ][o.e.x.s.a.l.LdapSessionFactory] [testCanAuthenticateAgainstBothRealms] Realm [external2] is in user-dn-template mode: [[cn={0},CN=Users,DC=ad,DC=test,DC=elasticsearch,DC=com]]
1> [2018-09-05T16:43:42,367][INFO ][o.e.x.s.a.s.FileRolesStore] [testCanAuthenticateAgainstBothRealms] parsed [5] roles from file [/home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0/temp/org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT_D87F1070EEC3E5AF-001/tempDir-003/SUITE-2/config/roles.yml]
1> [2018-09-05T16:43:42,369][INFO ][o.e.d.DiscoveryModule ] [testCanAuthenticateAgainstBothRealms] using discovery type [test-zen]
1> [2018-09-05T16:43:42,402][INFO ][o.e.n.Node ] [testCanAuthenticateAgainstBothRealms] initialized
1> [2018-09-05T16:43:42,404][INFO ][o.e.n.Node ] [integ_1] starting ...
1> [2018-09-05T16:43:42,404][INFO ][o.e.n.Node ] [integ_2] starting ...
1> [2018-09-05T16:43:42,404][INFO ][o.e.n.Node ] [integ_3] starting ...
1> [2018-09-05T16:43:42,433][INFO ][o.e.t.TransportService ] [integ_3] publish_address {127.0.0.1:30109}, bound_addresses {127.0.0.1:30109}
1> [2018-09-05T16:43:42,433][INFO ][o.e.t.TransportService ] [integ_2] publish_address {127.0.0.1:30108}, bound_addresses {127.0.0.1:30108}
1> [2018-09-05T16:43:42,451][INFO ][o.e.t.d.MockZenPing ] [node_sc2] pinging using mock zen ping
1> [2018-09-05T16:43:42,451][INFO ][o.e.t.d.MockZenPing ] [node_s1] pinging using mock zen ping
1> [2018-09-05T16:43:42,454][WARN ][o.e.t.d.TestZenDiscovery ] [node_sc2] not enough master nodes discovered during pinging (found [[Candidate{node={node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true}, clusterStateVersion=-1}]], but needed [2]), pinging again
1> [2018-09-05T16:43:42,454][WARN ][o.e.t.d.TestZenDiscovery ] [node_s1] not enough master nodes discovered during pinging (found [[Candidate{node={node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true}, clusterStateVersion=-1}]], but needed [2]), pinging again
1> [2018-09-05T16:43:42,455][INFO ][o.e.t.d.MockZenPing ] [node_s1] pinging using mock zen ping
1> [2018-09-05T16:43:42,455][INFO ][o.e.t.d.MockZenPing ] [node_sc2] pinging using mock zen ping
1> [2018-09-05T16:43:42,566][INFO ][o.e.t.TransportService ] [integ_1] publish_address {127.0.0.1:30107}, bound_addresses {127.0.0.1:30107}
1> [2018-09-05T16:43:42,568][INFO ][o.e.t.d.MockZenPing ] [node_s0] pinging using mock zen ping
1> [2018-09-05T16:43:42,976][INFO ][o.e.c.s.MasterService ] [node_s0] zen-disco-elected-as-master ([1] nodes joined)[, ], reason: master node changed {previous [], current [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}]}, added {{node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true},}
1> [2018-09-05T16:43:42,989][INFO ][o.e.c.s.ClusterApplierService] [node_s1] master node changed {previous [], current [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}]}, added {{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true},}, reason: apply cluster state (from master [master {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} committed version [1]])
1> [2018-09-05T16:43:42,991][INFO ][o.e.n.Node ] [integ_2] started
1> [2018-09-05T16:43:42,993][INFO ][o.e.c.s.ClusterApplierService] [node_s0] master node changed {previous [], current [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}]}, added {{node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true},}, reason: apply cluster state (from master [master {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} committed version [1] source [zen-disco-elected-as-master ([1] nodes joined)[, ]]])
1> [2018-09-05T16:43:42,997][INFO ][o.e.n.Node ] [integ_1] started
1> [2018-09-05T16:43:42,998][INFO ][o.e.c.s.MasterService ] [node_s0] zen-disco-node-join, reason: added {{node_sc2}{PUaiK3X-Tdiht0sfCxziFA}{iINZUJqAQJaVbHGC8H8nPg}{127.0.0.1}{127.0.0.1:30109}{xpack.installed=true},}
1> [2018-09-05T16:43:43,029][INFO ][o.e.c.s.ClusterApplierService] [node_s1] added {{node_sc2}{PUaiK3X-Tdiht0sfCxziFA}{iINZUJqAQJaVbHGC8H8nPg}{127.0.0.1}{127.0.0.1:30109}{xpack.installed=true},}, reason: apply cluster state (from master [master {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} committed version [2]])
1> [2018-09-05T16:43:43,048][INFO ][o.e.c.s.ClusterApplierService] [node_sc2] master node changed {previous [], current [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}]}, added {{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true},{node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true},}, reason: apply cluster state (from master [master {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} committed version [2]])
1> [2018-09-05T16:43:43,136][INFO ][o.e.n.Node ] [integ_3] started
1> [2018-09-05T16:43:43,137][INFO ][o.e.c.s.ClusterApplierService] [node_s0] added {{node_sc2}{PUaiK3X-Tdiht0sfCxziFA}{iINZUJqAQJaVbHGC8H8nPg}{127.0.0.1}{127.0.0.1:30109}{xpack.installed=true},}, reason: apply cluster state (from master [master {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} committed version [2] source [zen-disco-node-join]])
1> [2018-09-05T16:43:43,154][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[local_node], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear], request=[ClearRealmCacheRequest]
1> [2018-09-05T16:43:43,154][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[local_node], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear], request=[ClearRealmCacheRequest]
1> [2018-09-05T16:43:43,155][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[local_node], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,155][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[local_node], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,157][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,158][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,319][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,319][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[_xpack_security], realm=[__attach], roles=[superuser], action=[cluster:admin/xpack/security/realm/cache/clear[n]], request=[Node]
1> [2018-09-05T16:43:43,320][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:43,320][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:43,320][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:43,320][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:43,320][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.nio.MockNioTransportPlugin]
1> [2018-09-05T16:43:43,321][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.core.XPackClientPlugin]
1> [2018-09-05T16:43:43,323][INFO ][o.e.g.GatewayService ] [node_s0] recovered [0] indices into cluster_state
1> [2018-09-05T16:43:43,329][INFO ][o.e.x.s.a.TokenService ] [node_s1] refresh keys
1> [2018-09-05T16:43:43,329][INFO ][o.e.x.s.a.TokenService ] [node_sc2] refresh keys
1> [2018-09-05T16:43:43,450][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/liveness], request=[LivenessRequest]
1> [2018-09-05T16:43:43,575][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:43,575][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:43,598][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/delete], indices=[*,-*], request=[DeleteIndexRequest]
1> [2018-09-05T16:43:43,599][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/delete], indices=[*,-*], request=[DeleteIndexRequest]
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.nio.MockNioTransportPlugin]
1> [2018-09-05T16:43:43,610][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.core.XPackClientPlugin]
1> [2018-09-05T16:43:43,666][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/liveness], request=[LivenessRequest]
1> [2018-09-05T16:43:43,728][INFO ][o.e.x.s.a.TokenService ] [node_sc2] refreshed keys
1> [2018-09-05T16:43:43,734][INFO ][o.e.x.s.a.TokenService ] [node_s1] refreshed keys
1> [2018-09-05T16:43:43,769][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/get], request=[GetIndexTemplatesRequest]
1> [2018-09-05T16:43:43,769][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/get], request=[GetIndexTemplatesRequest]
1> [2018-09-05T16:43:43,777][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:43,778][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:43,808][INFO ][o.e.l.LicenseService ] [node_sc2] license [87e8226c-875f-4afd-9011-1cb554006067] mode [trial] - valid
1> [2018-09-05T16:43:43,810][INFO ][o.e.l.LicenseService ] [node_s1] license [87e8226c-875f-4afd-9011-1cb554006067] mode [trial] - valid
1> [2018-09-05T16:43:43,838][INFO ][o.e.l.LicenseService ] [node_s0] license [87e8226c-875f-4afd-9011-1cb554006067] mode [trial] - valid
1> [2018-09-05T16:43:43,838][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] removing template [security_audit_log]
1> [2018-09-05T16:43:43,849][INFO ][o.e.c.m.TemplateUpgradeService] [node_s0] Starting template upgrade to version 7.0.0-alpha1, 1 templates will be updated and 0 will be removed
1> [2018-09-05T16:43:43,854][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:admin/repository/delete], request=[DeleteRepositoryRequest]
1> [2018-09-05T16:43:43,854][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:admin/repository/delete], request=[DeleteRepositoryRequest]
1> [2018-09-05T16:43:43,989][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] adding template [security_audit_log] for index patterns [.security_audit_log*]
1> [2018-09-05T16:43:43,998][INFO ][o.e.c.m.TemplateUpgradeService] [node_s0] Templates were upgraded successfuly to version 7.0.0-alpha1
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] no modules loaded
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.analysis.common.CommonAnalysisPlugin]
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.index.reindex.ReindexPlugin]
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.transport.nio.MockNioTransportPlugin]
1> [2018-09-05T16:43:44,008][INFO ][o.e.p.PluginsService ] [testCanAuthenticateAgainstBothRealms] loaded plugin [org.elasticsearch.xpack.core.XPackClientPlugin]
1> [2018-09-05T16:43:44,152][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] adding template [random_index_template] for index patterns [*]
1> [2018-09-05T16:43:44,163][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] [MultipleAdRealmIT#testCanAuthenticateAgainstBothRealms]: all set up test
1> [2018-09-05T16:43:44,165][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/info], request=[NodesInfoRequest]
1> [2018-09-05T16:43:44,166][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/info], request=[NodesInfoRequest]
1> [2018-09-05T16:43:44,166][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[local_node], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/info[n]], request=[NodeInfoRequest]
1> [2018-09-05T16:43:44,167][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/info[n]], request=[NodeInfoRequest]
1> [2018-09-05T16:43:44,208][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:44,208][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:44,214][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:44,214][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:44,362][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/liveness], request=[LivenessRequest]
1> [2018-09-05T16:43:44,670][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/liveness], request=[LivenessRequest]
1> [2018-09-05T16:43:45,343][WARN ][o.e.x.s.a.AuthenticationService] [node_s1] Authentication to realm external failed - authenticate failed (Caused by LDAPException(resultCode=49 (invalid credentials), errorMessage='Simple Bind Failed: NT_STATUS_LOGON_FAILURE', diagnosticMessage='Simple Bind Failed: NT_STATUS_LOGON_FAILURE'))
1> [2018-09-05T16:43:45,344][WARN ][o.e.x.s.a.AuthenticationService] [node_s1] Authentication to realm external2 failed - authenticate failed (Caused by LDAPException(resultCode=49 (invalid credentials), errorMessage='Simple Bind Failed: NT_STATUS_PASSWORD_EXPIRED', diagnosticMessage='Simple Bind Failed: NT_STATUS_PASSWORD_EXPIRED'))
1> [2018-09-05T16:43:45,345][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [authentication_failed] origin_type=[transport], origin_address=[127.0.0.1], principal=[Natasha Romanoff], action=[indices:data/write/index], indices=[avengers], request=[IndexRequest]
1> [2018-09-05T16:43:45,392][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/nodes/liveness], request=[LivenessRequest]
1> [2018-09-05T16:43:45,414][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_superuser], realm=[file], roles=[superuser], action=[indices:admin/get], indices=[*,-*], request=[GetIndexRequest]
1> [2018-09-05T16:43:45,414][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_superuser], realm=[file], roles=[superuser], action=[indices:admin/get], indices=[*,-*], request=[GetIndexRequest]
1> [2018-09-05T16:43:45,418][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] [MultipleAdRealmIT#testCanAuthenticateAgainstBothRealms]: cleaning up after test
1> [2018-09-05T16:43:45,419][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,419][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,429][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/health], request=[ClusterHealthRequest]
1> [2018-09-05T16:43:45,429][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/health], request=[ClusterHealthRequest]
1> [2018-09-05T16:43:45,453][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,454][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,460][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,460][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:monitor/state], request=[ClusterStateRequest]
1> [2018-09-05T16:43:45,473][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/get], request=[GetIndexTemplatesRequest]
1> [2018-09-05T16:43:45,473][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/get], request=[GetIndexTemplatesRequest]
1> [2018-09-05T16:43:45,479][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:45,479][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_sc2] [127.0.0.1] [node_sc2] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:45,482][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] removing template [random_index_template]
1> [2018-09-05T16:43:45,493][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:45,493][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[indices:admin/template/delete], request=[DeleteIndexTemplateRequest]
1> [2018-09-05T16:43:45,495][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] removing template [security_audit_log]
1> [2018-09-05T16:43:45,503][INFO ][o.e.c.m.TemplateUpgradeService] [node_s0] Starting template upgrade to version 7.0.0-alpha1, 1 templates will be updated and 0 will be removed
1> [2018-09-05T16:43:45,507][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:admin/repository/delete], request=[DeleteRepositoryRequest]
1> [2018-09-05T16:43:45,507][INFO ][o.e.x.s.a.l.LoggingAuditTrail] [node_s1] [node_s1] [transport] [access_granted] origin_type=[transport], origin_address=[127.0.0.1], principal=[test_trans_client_user], realm=[file], roles=[transport_client,user], action=[cluster:admin/repository/delete], request=[DeleteRepositoryRequest]
1> [2018-09-05T16:43:45,510][INFO ][o.e.c.m.MetaDataIndexTemplateService] [node_s0] adding template [security_audit_log] for index patterns [.security_audit_log*]
1> [2018-09-05T16:43:45,518][INFO ][o.e.c.m.TemplateUpgradeService] [node_s0] Templates were upgraded successfuly to version 7.0.0-alpha1
1> [2018-09-05T16:43:45,524][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] [MultipleAdRealmIT#testCanAuthenticateAgainstBothRealms]: cleaned up after test
1> [2018-09-05T16:43:45,525][INFO ][o.e.x.s.a.l.MultipleAdRealmIT] [testCanAuthenticateAgainstBothRealms] after test
2> REPRODUCE WITH: ./gradlew :x-pack:qa:third-party:active-directory:test -Dtests.seed=D87F1070EEC3E5AF -Dtests.class=org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT -Dtests.method="testCanAuthenticateAgainstBothRealms" -Dtests.security.manager=true -Dtests.locale=en-IE -Dtests.timezone=Asia/Amman -Dcompiler.java=10 -Druntime.java=8
ERROR 7.20s | MultipleAdRealmIT.testCanAuthenticateAgainstBothRealms <<< FAILURES!
> Throwable #1: ElasticsearchSecurityException[unable to authenticate user [Natasha Romanoff] for action [indices:data/write/index]]
> at __randomizedtesting.SeedInfo.seed([D87F1070EEC3E5AF:D756EFB4222A869B]:0)
> at org.elasticsearch.xpack.core.security.support.Exceptions.authenticationError(Exceptions.java:18)
> at org.elasticsearch.xpack.core.security.authc.DefaultAuthenticationFailureHandler.createAuthenticationError(DefaultAuthenticationFailureHandler.java:129)
> at org.elasticsearch.xpack.core.security.authc.DefaultAuthenticationFailureHandler.failedAuthentication(DefaultAuthenticationFailureHandler.java:63)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$AuditableTransportRequest.authenticationFailed(AuthenticationService.java:507)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.consumeUser(AuthenticationService.java:360)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$14(AuthenticationService.java:298)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.core.common.IteratingActionListener.onResponse(IteratingActionListener.java:96)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$11(AuthenticationService.java:283)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.security.authc.support.CachingUsernamePasswordRealm.lambda$authenticateWithCache$4(CachingUsernamePasswordRealm.java:149)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.security.authc.esnative.NativeUsersStore.lambda$verifyPassword$16(NativeUsersStore.java:491)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.security.authc.esnative.NativeUsersStore.getUserAndPassword(NativeUsersStore.java:187)
> at org.elasticsearch.xpack.security.authc.esnative.NativeUsersStore.verifyPassword(NativeUsersStore.java:489)
> at org.elasticsearch.xpack.security.authc.esnative.NativeRealm.doAuthenticate(NativeRealm.java:42)
> at org.elasticsearch.xpack.security.authc.support.CachingUsernamePasswordRealm.authenticateWithCache(CachingUsernamePasswordRealm.java:137)
> at org.elasticsearch.xpack.security.authc.support.CachingUsernamePasswordRealm.authenticate(CachingUsernamePasswordRealm.java:80)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$13(AuthenticationService.java:264)
> at org.elasticsearch.xpack.core.common.IteratingActionListener.onResponse(IteratingActionListener.java:99)
> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$11(AuthenticationService.java:283)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.security.authc.support.CachingUsernamePasswordRealm.lambda$authenticateWithCache$4(CachingUsernamePasswordRealm.java:149)
> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60)
> at org.elasticsearch.xpack.security.authc.ldap.LdapRealm$LdapSessionActionListener.onFailure(LdapRealm.java:277)
> at org.elasticsearch.action.support.ContextPreservingActionListener.onFailure(ContextPreservingActionListener.java:50)
> at org.elasticsearch.xpack.security.authc.ldap.LdapSessionFactory$1.onFailure(LdapSessionFactory.java:96)
> at org.elasticsearch.xpack.security.authc.ldap.support.LdapUtils$2.onFailure(LdapUtils.java:187)
> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:39)
> at org.elasticsearch.xpack.security.authc.ldap.support.LdapUtils.maybeForkAndRun(LdapUtils.java:101)
> at org.elasticsearch.xpack.security.authc.ldap.support.LdapUtils.maybeForkThenBind(LdapUtils.java:195)
> at org.elasticsearch.xpack.security.authc.ldap.LdapSessionFactory$1.loop(LdapSessionFactory.java:106)
> at org.elasticsearch.xpack.security.authc.ldap.LdapSessionFactory.session(LdapSessionFactory.java:108)
> at org.elasticsearch.xpack.security.authc.ldap.LdapRealm.lambda$doAuthenticate$1(LdapRealm.java:129)
> at org.elasticsearch.xpack.security.authc.ldap.LdapRealm$CancellableLdapRunnable.doRun(LdapRealm.java:312)
> at org.elasticsearch.common.util.concurrent.ThreadContext$ContextPreservingAbstractRunnable.doRun(ThreadContext.java:723)
> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:37)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> at java.lang.Thread.run(Thread.java:748)
1> [2018-09-05T16:43:45,551][INFO ][o.e.n.Node ] [suite] stopping ...
1> [2018-09-05T16:43:45,556][INFO ][o.e.t.d.TestZenDiscovery ] [node_s1] master_left [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}], reason [shut_down]
1> [2018-09-05T16:43:45,556][WARN ][o.e.t.d.TestZenDiscovery ] [node_s1] master left (reason = shut_down), current nodes: nodes:
1> {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}, master
1> {node_sc2}{PUaiK3X-Tdiht0sfCxziFA}{iINZUJqAQJaVbHGC8H8nPg}{127.0.0.1}{127.0.0.1:30109}{xpack.installed=true}
1> {node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true}, local
1> [2018-09-05T16:43:45,557][INFO ][o.e.t.d.TestZenDiscovery ] [node_sc2] master_left [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}], reason [transport disconnected]
1> [2018-09-05T16:43:45,557][WARN ][o.e.t.d.TestZenDiscovery ] [node_sc2] master left (reason = transport disconnected), current nodes: nodes:
1> {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}, master
1> {node_sc2}{PUaiK3X-Tdiht0sfCxziFA}{iINZUJqAQJaVbHGC8H8nPg}{127.0.0.1}{127.0.0.1:30109}{xpack.installed=true}, local
1> {node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true}
1> [2018-09-05T16:43:45,558][INFO ][o.e.c.s.ClusterApplierService] [node_sc2] master node changed {previous [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}], current []}, reason: master left (reason = transport disconnected)
1> [2018-09-05T16:43:45,562][INFO ][o.e.c.s.ClusterApplierService] [node_s1] master node changed {previous [{node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true}], current []}, reason: master left (reason = shut_down)
1> [2018-09-05T16:43:45,562][INFO ][o.e.t.d.MockZenPing ] [node_s1] pinging using mock zen ping
1> [2018-09-05T16:43:45,562][INFO ][o.e.t.d.MockZenPing ] [node_sc2] pinging using mock zen ping
1> [2018-09-05T16:43:45,561][WARN ][o.e.c.NodeConnectionsService] [node_sc2] failed to connect to node {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} (tried [1] times)
1> org.elasticsearch.transport.ConnectTransportException: [node_s0][127.0.0.1:30107] connect_exception
1> at org.elasticsearch.transport.TcpChannel.awaitConnected(TcpChannel.java:116) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.openConnection(TcpTransport.java:456) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.openConnection(TcpTransport.java:118) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.ConnectionManager.internalOpenConnection(ConnectionManager.java:238) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.ConnectionManager.connectToNode(ConnectionManager.java:120) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TransportService.connectToNode(TransportService.java:370) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TransportService.connectToNode(TransportService.java:357) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.cluster.NodeConnectionsService.validateAndConnectIfNeeded(NodeConnectionsService.java:153) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.cluster.NodeConnectionsService$1.doRun(NodeConnectionsService.java:106) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.ThreadContext$ContextPreservingAbstractRunnable.doRun(ThreadContext.java:723) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:37) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_171]
1> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_171]
1> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_171]
1> Caused by: java.net.ConnectException: Connection refused
1> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) ~[?:?]
1> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717) ~[?:?]
1> at org.elasticsearch.nio.SocketChannelContext.connect(SocketChannelContext.java:116) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.EventHandler.handleConnect(EventHandler.java:97) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.attemptConnect(NioSelector.java:363) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.setUpNewChannels(NioSelector.java:380) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.preSelect(NioSelector.java:230) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.singleLoop(NioSelector.java:133) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.runLoop(NioSelector.java:109) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> ... 1 more
1> [2018-09-05T16:43:45,557][WARN ][o.e.x.s.t.n.SecurityNioTransport] [node_s1] send message failed [channel: TcpNioSocketChannel{localAddress=0.0.0.0/0.0.0.0:30108, remoteAddress=/127.0.0.1:39592}]
1> java.nio.channels.ClosedChannelException: null
1> at org.elasticsearch.nio.SocketChannelContext.sendMessage(SocketChannelContext.java:131) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.nio.NioTcpChannel.sendMessage(NioTcpChannel.java:41) ~[transport-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.internalSendMessage(TcpTransport.java:910) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.sendResponse(TcpTransport.java:999) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.sendResponse(TcpTransport.java:967) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransportChannel.sendResponse(TcpTransportChannel.java:66) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransportChannel.sendResponse(TcpTransportChannel.java:60) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TaskTransportChannel.sendResponse(TaskTransportChannel.java:54) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.discovery.zen.MembershipAction$LeaveRequestRequestHandler.messageReceived(MembershipAction.java:287) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.discovery.zen.MembershipAction$LeaveRequestRequestHandler.messageReceived(MembershipAction.java:282) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.SecurityServerTransportInterceptor$ProfileSecuredRequestHandler$1.doRun(SecurityServerTransportInterceptor.java:251) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:37) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.EsExecutors$1.execute(EsExecutors.java:135) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.SecurityServerTransportInterceptor$ProfileSecuredRequestHandler.lambda$messageReceived$0(SecurityServerTransportInterceptor.java:300) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.ServerTransportFilter$NodeProfile.lambda$inbound$2(ServerTransportFilter.java:135) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authz.AuthorizationUtils$AsyncAuthorizer.maybeRun(AuthorizationUtils.java:173) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authz.AuthorizationUtils$AsyncAuthorizer.setRunAsRoles(AuthorizationUtils.java:167) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authz.AuthorizationUtils$AsyncAuthorizer.authorize(AuthorizationUtils.java:149) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.ServerTransportFilter$NodeProfile.lambda$inbound$3(ServerTransportFilter.java:137) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:60) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$authenticateAsync$2(AuthenticationService.java:174) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$lookForExistingAuthentication$4(AuthenticationService.java:207) ~[x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lookForExistingAuthentication(AuthenticationService.java:218) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.authenticateAsync(AuthenticationService.java:172) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.access$000(AuthenticationService.java:133) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.authc.AuthenticationService.authenticate(AuthenticationService.java:103) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.ServerTransportFilter$NodeProfile.inbound(ServerTransportFilter.java:119) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.xpack.security.transport.SecurityServerTransportInterceptor$ProfileSecuredRequestHandler.messageReceived(SecurityServerTransportInterceptor.java:307) [x-pack-security-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.RequestHandlerRegistry.processMessageReceived(RequestHandlerRegistry.java:63) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport$RequestHandler.doRun(TcpTransport.java:1426) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.ThreadContext$ContextPreservingAbstractRunnable.doRun(ThreadContext.java:723) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:37) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_171]
1> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_171]
1> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_171]
1> [2018-09-05T16:43:45,567][INFO ][o.e.n.Node ] [suite] stopped
1> [2018-09-05T16:43:45,568][INFO ][o.e.n.Node ] [suite] closing ...
1> [2018-09-05T16:43:45,568][WARN ][o.e.c.NodeConnectionsService] [node_s1] failed to connect to node {node_s0}{TU-sRefuQXeteZQHG1tdnA}{0ZDgPu8JToyXXd0IdK7f7Q}{127.0.0.1}{127.0.0.1:30107}{xpack.installed=true} (tried [1] times)
1> org.elasticsearch.transport.ConnectTransportException: [node_s0][127.0.0.1:30107] connect_exception
1> at org.elasticsearch.transport.TcpChannel.awaitConnected(TcpChannel.java:116) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.openConnection(TcpTransport.java:456) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TcpTransport.openConnection(TcpTransport.java:118) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.ConnectionManager.internalOpenConnection(ConnectionManager.java:238) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.ConnectionManager.connectToNode(ConnectionManager.java:120) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TransportService.connectToNode(TransportService.java:370) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.transport.TransportService.connectToNode(TransportService.java:357) ~[elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.cluster.NodeConnectionsService.validateAndConnectIfNeeded(NodeConnectionsService.java:153) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.cluster.NodeConnectionsService$1.doRun(NodeConnectionsService.java:106) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.ThreadContext$ContextPreservingAbstractRunnable.doRun(ThreadContext.java:723) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:37) [elasticsearch-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_171]
1> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_171]
1> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_171]
1> Caused by: java.net.ConnectException: Connection refused
1> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) ~[?:?]
1> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717) ~[?:?]
1> at org.elasticsearch.nio.SocketChannelContext.connect(SocketChannelContext.java:116) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.EventHandler.handleConnect(EventHandler.java:97) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.attemptConnect(NioSelector.java:363) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.setUpNewChannels(NioSelector.java:380) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.preSelect(NioSelector.java:230) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.singleLoop(NioSelector.java:133) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> at org.elasticsearch.nio.NioSelector.runLoop(NioSelector.java:109) ~[elasticsearch-nio-7.0.0-alpha1-SNAPSHOT.jar:7.0.0-alpha1-SNAPSHOT]
1> ... 1 more
1> [2018-09-05T16:43:45,575][INFO ][o.e.n.Node ] [suite] closed
1> [2018-09-05T16:43:45,579][INFO ][o.e.n.Node ] [suite] stopping ...
1> [2018-09-05T16:43:45,579][WARN ][o.e.t.d.TestZenDiscovery ] [node_sc2] not enough master nodes discovered during pinging (found [[]], but needed [2]), pinging again
1> [2018-09-05T16:43:45,579][WARN ][o.e.t.d.TestZenDiscovery ] [node_s1] not enough master nodes discovered during pinging (found [[Candidate{node={node_s1}{TdSvhFb-Q5mf1ic6UGPIiA}{tHcG59DoTy64bhI-h4im7Q}{127.0.0.1}{127.0.0.1:30108}{xpack.installed=true}, clusterStateVersion=11}]], but needed [2]), pinging again
1> [2018-09-05T16:43:45,579][INFO ][o.e.t.d.MockZenPing ] [node_sc2] pinging using mock zen ping
1> [2018-09-05T16:43:45,582][INFO ][o.e.n.Node ] [suite] stopped
1> [2018-09-05T16:43:45,582][INFO ][o.e.n.Node ] [suite] closing ...
1> [2018-09-05T16:43:45,583][INFO ][o.e.n.Node ] [suite] closed
1> [2018-09-05T16:43:45,586][INFO ][o.e.n.Node ] [suite] stopping ...
1> [2018-09-05T16:43:45,586][WARN ][o.e.t.d.TestZenDiscovery ] [node_sc2] not enough master nodes discovered during pinging (found [[]], but needed [2]), pinging again
1> [2018-09-05T16:43:45,587][INFO ][o.e.n.Node ] [suite] stopped
1> [2018-09-05T16:43:45,587][INFO ][o.e.n.Node ] [suite] closing ...
1> [2018-09-05T16:43:45,588][INFO ][o.e.n.Node ] [suite] closed
2> NOTE: leaving temporary files on disk at: /home/jake/workspace/elasticsearch/x-pack/qa/third-party/active-directory/build/testrun/test/J0/temp/org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT_D87F1070EEC3E5AF-001
2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=891, maxMBSortInHeap=5.201501804589987, sim=RandomSimilarity(queryNorm=true): {}, locale=en-IE, timezone=Asia/Amman
2> NOTE: Linux 4.17.4-200.fc28.x86_64 amd64/Oracle Corporation 1.8.0_171 (64-bit)/cpus=8,threads=1,free=279848296,total=477626368
2> NOTE: All tests run in this JVM: [MultipleAdRealmIT]
Completed [1/1] in 9.29s, 1 test, 1 error <<< FAILURES!
> Task :x-pack:qa:third-party:active-directory:test
Tests with failures:
- org.elasticsearch.xpack.security.authc.ldap.MultipleAdRealmIT.testCanAuthenticateAgainstBothRealms
[junit4] JVM J0: 0.34 .. 10.33 = 9.99s
[junit4] Execution time total: 10 seconds
[junit4] Tests summary: 1 suite, 1 test, 1 error
> Task :x-pack:qa:third-party:active-directory:test FAILED
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':x-pack:qa:third-party:active-directory:test'.
> There were test failures: 1 suite, 1 test, 1 error [seed: D87F1070EEC3E5AF]
* Try:
Run with --stacktrace option to get the stack trace. Run with --info or --debug option to get more log output. Run with --scan to get full insights.
* Get more help at https://help.gradle.org
Deprecated Gradle features were used in this build, making it incompatible with Gradle 5.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/4.10/userguide/command_line_interface.html#sec:command_line_warnings
BUILD FAILED in 1m 47s
68 actionable tasks: 61 executed, 7 up-to-date
