You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/30 15:07:54 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 362 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/362/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData
Error Message:
Node watch should have fired!
Stack Trace:
java.lang.AssertionError: Node watch should have fired!
at __randomizedtesting.SeedInfo.seed([56D238AB962C3614:7042D7F9C2A2F01E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13617 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager
[junit4] 2> 4337988 INFO (SUITE-TestDistribStateManager-seed#[56D238AB962C3614]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestDistribStateManager_56D238AB962C3614-001/init-core-data-001
[junit4] 2> 4337989 INFO (SUITE-TestDistribStateManager-seed#[56D238AB962C3614]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4337990 INFO (SUITE-TestDistribStateManager-seed#[56D238AB962C3614]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 4338003 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateMode
[junit4] 2> 4338003 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4338003 INFO (Thread-7586) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4338003 INFO (Thread-7586) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4338011 ERROR (Thread-7586) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4338104 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer start zk server on port:49859
[junit4] 2> 4338132 INFO (zkConnectionManagerCallback-6878-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4338132 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4338168 INFO (zkConnectionManagerCallback-6880-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4338168 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4338181 ERROR (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4338184 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49859 49859
[junit4] 2> 4343319 INFO (Thread-7586) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49859 49859
[junit4] 2> 4343324 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateMode
[junit4] 2> 4343336 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListData
[junit4] 2> 4343337 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4343337 INFO (Thread-7587) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4343337 INFO (Thread-7587) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4343341 ERROR (Thread-7587) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4343444 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer start zk server on port:38850
[junit4] 2> 4343451 INFO (zkConnectionManagerCallback-6882-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4343452 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4343463 ERROR (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4343473 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38850 38850
[junit4] 2> 4354387 INFO (Thread-7587) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38850 38850
[junit4] 2> 4354410 INFO (TEST-TestDistribStateManager.testListData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListData
[junit4] 2> 4354438 INFO (TEST-TestDistribStateManager.testHasData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHasData
[junit4] 2> 4354438 INFO (TEST-TestDistribStateManager.testHasData-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 4354438 INFO (TEST-TestDistribStateManager.testHasData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHasData
[junit4] 2> 4354459 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetSetRemoveData
[junit4] 2> 4354459 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4354459 INFO (Thread-7588) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4354459 INFO (Thread-7588) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4354464 ERROR (Thread-7588) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4354559 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer start zk server on port:35230
[junit4] 2> 4354570 INFO (zkConnectionManagerCallback-6884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4354570 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4354634 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160a7de288b0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4354664 INFO (zkConnectionManagerCallback-6886-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4354667 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4359676 ERROR (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4359680 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35230 35230
[junit4] 2> 4370204 INFO (Thread-7588) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35230 35230
[junit4] 2> 4370208 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetSetRemoveData
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistribStateManager -Dtests.method=testGetSetRemoveData -Dtests.seed=56D238AB962C3614 -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=Africa/El_Aaiun -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 15.8s J1 | TestDistribStateManager.testGetSetRemoveData <<<
[junit4] > Throwable #1: java.lang.AssertionError: Node watch should have fired!
[junit4] > at __randomizedtesting.SeedInfo.seed([56D238AB962C3614:7042D7F9C2A2F01E]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4370236 INFO (TEST-TestDistribStateManager.testMulti-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMulti
[junit4] 2> 4370236 INFO (TEST-TestDistribStateManager.testMulti-seed#[56D238AB962C3614]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 4370236 INFO (TEST-TestDistribStateManager.testMulti-seed#[56D238AB962C3614]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMulti
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestDistribStateManager_56D238AB962C3614-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=288, maxMBSortInHeap=7.178721988102415, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn, timezone=Africa/El_Aaiun
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=119397976,total=517996544
[junit4] 2> NOTE: All tests run in this JVM: [SegmentsInfoRequestHandlerTest, ResponseHeaderTest, TestJavabinTupleStreamParser, TestSolr4Spatial2, DistributedQueueTest, FieldMutatingUpdateProcessorTest, TestImpersonationWithHadoopAuth, TestCoreBackup, SolrCloudExampleTest, TestRandomFaceting, TestConfigSetProperties, ShardSplitTest, UpdateParamsTest, TestPayloadScoreQParserPlugin, TriggerIntegrationTest, DeleteStatusTest, TestMultiWordSynonyms, TestPartialUpdateDeduplication, TestDocBasedVersionConstraints, SolrMetricManagerTest, OverseerRolesTest, TestManagedSchemaThreadSafety, CdcrReplicationHandlerTest, MoreLikeThisHandlerTest, AtomicUpdateProcessorFactoryTest, RequestLoggingTest, TestCharFilters, RegexBytesRefFilterTest, MBeansHandlerTest, DateRangeFieldTest, PropertiesRequestHandlerTest, TestFoldingMultitermQuery, LegacyCloudClusterPropTest, FileUtilsTest, DistributedFacetExistsSmallTest, TestMergePolicyConfig, TestCrossCoreJoin, ShufflingReplicaListTransformerTest, PreAnalyzedFieldTest, SmileWriterTest, BigEndianAscendingWordSerializerTest, TestScoreJoinQPScore, TestNodeLostTrigger, PingRequestHandlerTest, BasicAuthIntegrationTest, TestFieldSortValues, TermVectorComponentDistributedTest, TestFastLRUCache, TestInfoStreamLogging, UpdateRequestProcessorFactoryTest, SynonymTokenizerTest, InfixSuggestersTest, TestStressLiveNodes, TestCorePropertiesReload, TestPerFieldSimilarity, PKIAuthenticationIntegrationTest, SpatialRPTFieldTypeTest, TestOnReconnectListenerSupport, EchoParamsTest, DirectSolrSpellCheckerTest, TestLegacyNumericUtils, SparseHLLTest, MultiThreadedOCPTest, TestConfigsApi, TestJsonFacets, HLLSerializationTest, ConnectionManagerTest, SuggestComponentContextFilterQueryTest, HdfsLockFactoryTest, TestSimDistributedQueue, JsonLoaderTest, SolrCoreTest, BufferStoreTest, DistributedFacetSimpleRefinementLongTailTest, ZkStateWriterTest, LeaderInitiatedRecoveryOnCommitTest, TestRebalanceLeaders, TestStressReorder, TestOrdValues, SolrCloudReportersTest, RecoveryAfterSoftCommitTest, DistributedIntervalFacetingTest, TestSolrConfigHandlerConcurrent, DeleteLastCustomShardedReplicaTest, TestExactStatsCache, MissingSegmentRecoveryTest, CloudExitableDirectoryReaderTest, HdfsTlogReplayBufferedWhileIndexingTest, DistribDocExpirationUpdateProcessorTest, TestSchemaResource, TestPullReplica, TestSolrConfigHandler, TestFieldTypeResource, TestMacros, TestWordDelimiterFilterFactory, TestInPlaceUpdatesStandalone, OpenCloseCoreStressTest, TestReplicationHandlerBackup, TestPKIAuthenticationPlugin, TestHdfsUpdateLog, CollectionsAPIAsyncDistributedZkTest, TestCustomDocTransformer, OverseerTaskQueueTest, QueryEqualityTest, TestMaxTokenLenTokenizer, TestDynamicFieldResource, BlockJoinFacetRandomTest, CdcrUpdateLogTest, TestTrackingShardHandlerFactory, TestJettySolrRunner, TestRandomCollapseQParserPlugin, TestStressUserVersions, HdfsChaosMonkeySafeLeaderTest, TestIndexSearcher, AutoAddReplicasPlanActionTest, MetricTriggerTest, SortSpecParsingTest, SolrCoreCheckLockOnStartupTest, GraphQueryTest, TestUtilizeNode, ParsingFieldUpdateProcessorsTest, SimpleCollectionCreateDeleteTest, TestNestedDocsSort, CoreAdminRequestStatusTest, SchemaVersionSpecificBehaviorTest, BasicFunctionalityTest, TestDocSet, PeerSyncWithIndexFingerprintCachingTest, TestLegacyField, DocValuesMultiTest, WrapperMergePolicyFactoryTest, TestStreamBody, TestBlendedInfixSuggestions, TestConfigOverlay, TestFunctionQuery, SOLR749Test, TermsComponentTest, TestGroupingSearch, TestCloudInspectUtil, TestClusterProperties, RestartWhileUpdatingTest, TestCollationField, SoftAutoCommitTest, DeleteReplicaTest, PreAnalyzedFieldManagedSchemaCloudTest, DOMUtilTest, LeaderFailoverAfterPartitionTest, ZkFailoverTest, TestSha256AuthenticationProvider, CoreSorterTest, TestFreeTextSuggestions, TestNamedUpdateProcessors, TestFastOutputStream, TestRestoreCore, TestDistributedStatsComponentCardinality, TestZkChroot, TestRemoteStreaming, SolrMetricsIntegrationTest, TestSimpleTrackingShardHandler, MetricsConfigTest, DistanceUnitsTest, ClassificationUpdateProcessorFactoryTest, HdfsWriteToMultipleCollectionsTest, DistributedFacetPivotSmallTest, BJQParserTest, DocumentAnalysisRequestHandlerTest, TestLegacyFieldCache, TestXmlQParser, TestFieldCacheVsDocValues, CdcrVersionReplicationTest, TestEmbeddedSolrServerConstructors, TestHttpShardHandlerFactory, TestElisionMultitermQuery, TestSort, SpellPossibilityIteratorTest, SSLMigrationTest, TestRTimerTree, SpellingQueryConverterTest, TestBlobHandler, InfoHandlerTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestCollationFieldDocValues, DocValuesNotIndexedTest, CursorMarkTest, TestQueryUtils, StatelessScriptUpdateProcessorFactoryTest, TestRawResponseWriter, TestSolrFieldCacheBean, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, ShardRoutingCustomTest, TestDistributedGrouping, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TestReload, TestStressVersions, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, PeerSyncTest, TestBadConfig, SolrIndexSplitterTest, TestCoreDiscovery, CoreAdminHandlerTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestTrie, SpatialFilterTest, TestCSVLoader, PolyFieldTest, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestSolrDeletionPolicy1, CacheHeaderTest, LukeRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, TestAnalyzedSuggestions, TestComponentsName, TestLFUCache, TestQuerySenderListener, CopyFieldTest, SolrIndexConfigTest, MultiTermTest, SampleTest, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, TestLuceneMatchVersion, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, TimeZoneUtilsTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, AssignTest, CollectionReloadTest, CollectionsAPISolrJTest, LeaderElectionContextKeyTest, LeaderFailureAfterFreshStartTest, MoveReplicaHDFSTest, SaslZkACLProviderTest, SolrCLIZkUtilsTest, TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCloudSearcherWarming, TestConfigSetsAPI, TestDistribDocBasedVersion, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestLockTree, TestPrepRecovery, TestRequestForwarding, TestRequestStatusCollectionAPI, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestStressInPlaceUpdates, AutoAddReplicasIntegrationTest, HttpTriggerListenerTest, NodeAddedTriggerTest, NodeLostTriggerTest, SystemLogListenerTest, TestClusterStateProvider, TestComputePlanAction, TestDistribStateManager]
[junit4] Completed [586/764 (1!)] on J1 in 32.28s, 5 tests, 1 failure <<< FAILURES!
[...truncated 48726 lines...]
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 364 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/364/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth
Error Message:
2 threads leaked from SUITE scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth: 1) Thread[id=897, name=jetty-launcher-183-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505) 2) Thread[id=892, name=jetty-launcher-183-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
1) Thread[id=897, name=jetty-launcher-183-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
2) Thread[id=892, name=jetty-launcher-183-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
at __randomizedtesting.SeedInfo.seed([4FA08EB5C8EE72BA]:0)
Build Log:
[...truncated 11753 lines...]
[junit4] Suite: org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth
[junit4] 2> 76914 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_4FA08EB5C8EE72BA-001/init-core-data-001
[junit4] 2> 76915 WARN (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24
[junit4] 2> 76915 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 76916 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 76965 WARN (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
[junit4] 2> 77066 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_4FA08EB5C8EE72BA-001/tempDir-001
[junit4] 2> 77066 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 77066 INFO (Thread-186) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 77066 INFO (Thread-186) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 77069 ERROR (Thread-186) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 77166 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44290
[junit4] 2> 77171 INFO (zkConnectionManagerCallback-186-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77187 INFO (jetty-launcher-183-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 77187 INFO (jetty-launcher-183-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 77191 INFO (jetty-launcher-183-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b120117{/solr,null,AVAILABLE}
[junit4] 2> 77198 INFO (jetty-launcher-183-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d311926{HTTP/1.1,[http/1.1]}{127.0.0.1:45230}
[junit4] 2> 77198 INFO (jetty-launcher-183-thread-2) [ ] o.e.j.s.Server Started @79207ms
[junit4] 2> 77198 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45230}
[junit4] 2> 77199 ERROR (jetty-launcher-183-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 77199 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 77199 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 77199 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 77199 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T11:16:14.955Z
[junit4] 2> 77200 INFO (jetty-launcher-183-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56597460{/solr,null,AVAILABLE}
[junit4] 2> 77200 INFO (jetty-launcher-183-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50e2b5bd{HTTP/1.1,[http/1.1]}{127.0.0.1:65451}
[junit4] 2> 77200 INFO (jetty-launcher-183-thread-1) [ ] o.e.j.s.Server Started @79209ms
[junit4] 2> 77200 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65451}
[junit4] 2> 77201 ERROR (jetty-launcher-183-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 77201 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 77201 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 77201 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 77201 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-31T11:16:14.957Z
[junit4] 2> 77206 INFO (zkConnectionManagerCallback-188-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77207 INFO (zkConnectionManagerCallback-190-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77207 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 77208 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 77223 INFO (jetty-launcher-183-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 77223 INFO (jetty-launcher-183-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44290/solr
[junit4] 2> 77227 INFO (zkConnectionManagerCallback-198-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77228 INFO (zkConnectionManagerCallback-196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77230 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160ac4953680003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 77237 INFO (zkConnectionManagerCallback-202-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77239 INFO (zkConnectionManagerCallback-201-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77369 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 77370 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45230_solr
[junit4] 2> 77372 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.Overseer Overseer (id=99268622734065669-127.0.0.1:45230_solr-n_0000000000) starting
[junit4] 2> 77375 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 77377 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65451_solr
[junit4] 2> 77379 INFO (zkCallback-200-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 77381 INFO (zkCallback-199-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 77387 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45230_solr
[junit4] 2> 77391 INFO (zkCallback-200-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 77393 INFO (zkCallback-199-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 77467 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 77481 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.s.HadoopAuthPlugin Params: {proxyuser.anyHostUsersGroup.hosts=*, proxyuser.wrongHost.hosts=1.1.1.1.1.1, proxyuser.bogusGroup.groups=__some_bogus_group, proxyuser.localHostAnyGroup.groups=*, cookie.domain=127.0.0.1, proxyuser.wrongHost.groups=*, type=simple, proxyuser.noHosts.groups=*, proxyuser.anyHostUsersGroup.groups=staff, proxyuser.noGroups.hosts=*, proxyuser.anyHostAnyUser.groups=*, delegation-token.token-kind=solr-dt, proxyuser.bogusGroup.hosts=*, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, zk-dt-secret-manager.enable=true, proxyuser.localHostAnyGroup.hosts=serv1-vm3,localhost,127.0.0.1, proxyuser.anyHostAnyUser.hosts=*, signer.secret.provider=zookeeper}
[junit4] 2> 77557 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 77578 INFO (jetty-launcher-183-thread-1-EventThread) [n:127.0.0.1:65451_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 77602 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 77602 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.s.HadoopAuthPlugin Params: {proxyuser.anyHostUsersGroup.hosts=*, proxyuser.wrongHost.hosts=1.1.1.1.1.1, proxyuser.bogusGroup.groups=__some_bogus_group, proxyuser.localHostAnyGroup.groups=*, cookie.domain=127.0.0.1, proxyuser.wrongHost.groups=*, type=simple, proxyuser.noHosts.groups=*, proxyuser.anyHostUsersGroup.groups=staff, proxyuser.noGroups.hosts=*, proxyuser.anyHostAnyUser.groups=*, delegation-token.token-kind=solr-dt, proxyuser.bogusGroup.hosts=*, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, zk-dt-secret-manager.enable=true, proxyuser.localHostAnyGroup.hosts=serv1-vm3,localhost,127.0.0.1, proxyuser.anyHostAnyUser.hosts=*, signer.secret.provider=zookeeper}
[junit4] 2> 77605 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 77608 INFO (jetty-launcher-183-thread-2-EventThread) [n:127.0.0.1:45230_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 77831 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 77831 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 77878 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45230.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77878 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65451.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77886 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45230.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77886 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45230.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77887 INFO (jetty-launcher-183-thread-2) [n:127.0.0.1:45230_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_4FA08EB5C8EE72BA-001/tempDir-001/node2/.
[junit4] 2> 77888 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65451.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77888 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65451.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e426e18
[junit4] 2> 77889 INFO (jetty-launcher-183-thread-1) [n:127.0.0.1:65451_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_4FA08EB5C8EE72BA-001/tempDir-001/node1/.
[junit4] 2> 77926 INFO (zkConnectionManagerCallback-208-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77942 INFO (zkConnectionManagerCallback-212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77944 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 77945 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44290/solr ready
[junit4] 2> 77973 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigGroups
[junit4] 2> 78296 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=noGroups&p=noGroups&t=simple-dt&e=1514754975739&s=k+7q7gwXRPE/PP4+0Wbdf6Y6tgo="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:15 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:15 GMT
[junit4] 2> 78298 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigGroups
[junit4] IGNOR/A 0.00s J1 | TestImpersonationWithHadoopAuth.testForwarding
[junit4] > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/HADOOP-9893))
[junit4] 2> 78319 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigHosts
[junit4] 2> 78326 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=noHosts&p=noHosts&t=simple-dt&e=1514754976077&s=HNWKo3n+oYuYvmAeRpNRMDPm5dI="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 78326 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigHosts
[junit4] 2> 78348 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNullProxyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNullProxyUser
[junit4] 2> 78350 INFO (qtp1089817509-845) [ ] o.a.s.s.SolrDispatchFilter Error authenticating
[junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empty or contains a '&'
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at org.apache.hadoop.security.authentication.server.PseudoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195)
[junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at org.apache.solr.security.HadoopAuthFilter.doFilter(HadoopAuthFilter.java:110)
[junit4] 2> at org.apache.solr.security.HadoopAuthPlugin.doAuthenticate(HadoopAuthPlugin.java:245)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:451)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 78351 WARN (qtp1089817509-845) [ ] o.e.j.s.ServletHandler /solr/admin/collections
[junit4] 2> org.apache.solr.common.SolrException: Error during request authentication,
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:457)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is NULL, empty or contains a '&'
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at org.apache.hadoop.security.authentication.server.PseudoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195)
[junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at org.apache.solr.security.HadoopAuthFilter.doFilter(HadoopAuthFilter.java:110)
[junit4] 2> at org.apache.solr.security.HadoopAuthPlugin.doAuthenticate(HadoopAuthPlugin.java:245)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:451)
[junit4] 2> ... 26 more
[junit4] 2> 78354 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyNullProxyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNullProxyUser
[junit4] 2> 78378 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidProxyUser
[junit4] 2> 78382 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=simple-dt&e=1514754976136&s=GkSO7YO27QyAI62/YqJASiMHpLw="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 78382 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidProxyUser
[junit4] 2> 78411 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateHost
[junit4] 2> 78416 INFO (qtp1089817509-849) [n:127.0.0.1:45230_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=bar&action=LIST&user.name=localHostAnyGroup&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 78416 INFO (qtp1089817509-849) [n:127.0.0.1:45230_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=bar&action=LIST&user.name=localHostAnyGroup&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78417 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=simple-dt&e=1514754976169&s=k3opr956OotVGVsVMV53pv5rV0Y="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 78417 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateHost
[junit4] 2> 78442 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateAnyHostAnyUser
[junit4] 2> 78444 INFO (qtp1089817509-848) [n:127.0.0.1:45230_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=bar&action=LIST&user.name=anyHostAnyUser&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 78444 INFO (qtp1089817509-848) [n:127.0.0.1:45230_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=bar&action=LIST&user.name=anyHostAnyUser&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78445 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=anyHostAnyUser&p=anyHostAnyUser&t=simple-dt&e=1514754976200&s=tZO6UMHZQxFMFVDtK+qi+SsTZbE="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 78445 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateAnyHostAnyUser
[junit4] 2> 78471 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateGroup
[junit4] 2> 78509 INFO (qtp1089817509-845) [n:127.0.0.1:45230_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=jenkins&action=LIST&user.name=anyHostUsersGroup&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 78509 INFO (qtp1089817509-845) [n:127.0.0.1:45230_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=jenkins&action=LIST&user.name=anyHostUsersGroup&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78509 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=anyHostUsersGroup&p=anyHostUsersGroup&t=simple-dt&e=1514754976229&s=+vOmuGNeWlVE0VywQO9a2CKBjAg="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 78510 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateGroup
[junit4] 2> 78536 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyWrongHost
[junit4] 2> 94441 WARN (jetty-launcher-183-thread-1-SendThread(127.0.0.1:44290)) [n:127.0.0.1:65451_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16547ms for sessionid 0x160ac4953680006
[junit4] 2> 94442 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160ac4953680006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 94442 WARN (jetty-launcher-183-thread-1-SendThread(127.0.0.1:44290)) [n:127.0.0.1:65451_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16636ms for sessionid 0x160ac4953680007
[junit4] 2> 94442 WARN (jetty-launcher-183-thread-2-SendThread(127.0.0.1:44290)) [n:127.0.0.1:45230_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16637ms for sessionid 0x160ac4953680008
[junit4] 2> 94442 WARN (jetty-launcher-183-thread-2-SendThread(127.0.0.1:44290)) [n:127.0.0.1:45230_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16554ms for sessionid 0x160ac4953680005
[junit4] 2> 94444 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160ac4953680008, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 94444 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160ac4953680007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 94445 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160ac4953680005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 94446 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=wrongHost&p=wrongHost&t=simple-dt&e=1514754976294&s=4sk01ZjI0OJ3/LWeKiRD4jvfcsA="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:16 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:16 GMT
[junit4] 2> 94446 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyWrongHost
[junit4] 2> 94488 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidGroup
[junit4] 2> 94505 WARN (qtp1089817509-845) [n:127.0.0.1:45230_solr ] o.a.h.s.ShellBasedUnixGroupsMapping got exception trying to get groups for user bar: id: invalid user name: "bar"
[junit4] 2> id: invalid user name: "bar"
[junit4] 2>
[junit4] 2> 94507 WARN (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bogusGroup&p=bogusGroup&t=simple-dt&e=1514754992247&s=PXwNGj4Idluba3thMTYtX0dK4B4="; Domain=127.0.0.1; Expires=Ne, 31-Pro-2017 21:16:32 GMT; HttpOnly". Invalid 'expires' attribute: Ne, 31-Pro-2017 21:16:32 GMT
[junit4] 2> 94507 INFO (TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[4FA08EB5C8EE72BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidGroup
[junit4] 2> 94543 WARN (zkConnectionManagerCallback-201-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e42125f name: ZooKeeperConnection Watcher:127.0.0.1:44290/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 94543 WARN (zkConnectionManagerCallback-201-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 94553 INFO (jetty-launcher-183-thread-2-EventThread) [n:127.0.0.1:45230_solr ] o.a.c.f.s.ConnectionStateManager State change: SUSPENDED
[junit4] 2> 94553 INFO (jetty-launcher-183-thread-1-EventThread) [n:127.0.0.1:65451_solr ] o.a.c.f.s.ConnectionStateManager State change: SUSPENDED
[junit4] 2> 94560 WARN (zkConnectionManagerCallback-202-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f7ee67e name: ZooKeeperConnection Watcher:127.0.0.1:44290/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 94560 WARN (zkConnectionManagerCallback-202-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 94562 INFO (jetty-closer-184-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@50e2b5bd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 94563 INFO (jetty-closer-184-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1088454615
[junit4] 2> 94563 INFO (jetty-closer-184-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6d311926{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 94564 INFO (jetty-closer-184-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=4704530
[junit4] 2> 94564 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 94565 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1de31a45: rootName = solr_45230, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3fa8518
[junit4] 2> 94565 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 94568 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f95d038: rootName = solr_65451, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@47d975db
[junit4] 2> 94577 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 94577 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1651d894: rootName = solr_45230, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@34118c0d
[junit4] 2> 94578 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 94578 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@246b0006: rootName = solr_65451, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@34118c0d
[junit4] 2> 94581 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 94581 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f779bba: rootName = solr_45230, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@786766a2
[junit4] 2> 94582 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 94582 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a4add8f: rootName = solr_65451, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@786766a2
[junit4] 2> 95848 INFO (zkConnectionManagerCallback-202-thread-1-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 95850 INFO (jetty-closer-184-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45230_solr
[junit4] 2> 95851 INFO (jetty-closer-184-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 95851 INFO (jetty-closer-184-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 95851 INFO (jetty-closer-184-thread-1) [ ] o.a.s.c.Overseer Overseer (id=99268622734065669-127.0.0.1:45230_solr-n_0000000000) closing
[junit4] 2> 95852 INFO (OverseerStateUpdate-99268622734065669-127.0.0.1:45230_solr-n_0000000000) [n:127.0.0.1:45230_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45230_solr
[junit4] 2> 95852 WARN (OverseerAutoScalingTriggerThread-99268622734065669-127.0.0.1:45230_solr-n_0000000000) [n:127.0.0.1:45230_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 95853 INFO (zkCallback-200-thread-2-processing-n:127.0.0.1:45230_solr) [n:127.0.0.1:45230_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 95853 INFO (OverseerCollectionConfigSetProcessor-99268622734065669-127.0.0.1:45230_solr-n_0000000000) [n:127.0.0.1:45230_solr ] o.a.s.c.OverseerTaskProcessor According to ZK I (id=99268622734065669-127.0.0.1:45230_solr-n_0000000000) am no longer a leader.
[junit4] 2> 95857 ERROR (Thread[Thread-204,5,TGRP-TestImpersonationWithHadoopAuth]) [n:127.0.0.1:45230_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 95904 INFO (zkConnectionManagerCallback-201-thread-1-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 95906 INFO (zkCallback-199-thread-2-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:65451_solr
[junit4] 2> 95906 INFO (zkCallback-199-thread-4-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 95908 INFO (jetty-closer-184-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:65451_solr
[junit4] 2> 95910 INFO (jetty-closer-184-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 95910 INFO (jetty-closer-184-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 95913 INFO (zkCallback-199-thread-4-processing-n:127.0.0.1:65451_solr) [n:127.0.0.1:65451_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 95917 ERROR (Thread[Thread-203,5,TGRP-TestImpersonationWithHadoopAuth]) [n:127.0.0.1:65451_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 95927 INFO (jetty-closer-184-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56597460{/solr,null,UNAVAILABLE}
[junit4] 2> 96513 INFO (jetty-closer-184-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b120117{/solr,null,UNAVAILABLE}
[junit4] 2> 96515 ERROR (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 96516 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44290 44290
[junit4] 2> 100190 INFO (Thread-186) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44290 44290
[junit4] 2> 100192 WARN (Thread-186) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> 100193 INFO (SUITE-TestImpersonationWithHadoopAuth-seed#[4FA08EB5C8EE72BA]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Dec 31, 2017 11:16:37 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> Dec 31, 2017 11:16:58 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 2 threads leaked from SUITE scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
[junit4] 2> 1) Thread[id=897, name=jetty-launcher-183-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> 2) Thread[id=892, name=jetty-launcher-183-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> Dec 31, 2017 11:16:58 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=897, name=jetty-launcher-183-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> 2) Thread[id=892, name=jetty-launcher-183-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> 120351 ERROR (jetty-launcher-183-thread-2-EventThread) [n:127.0.0.1:45230_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exception
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> 120351 ERROR (jetty-launcher-183-thread-1-EventThread) [n:127.0.0.1:65451_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exception
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> Dec 31, 2017 11:16:58 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1227, maxMBSortInHeap=6.838561828256362, sim=RandomSimilarity(queryNorm=false): {}, locale=cs-CZ, timezone=Cuba
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=28293216,total=135741440
[junit4] 2> NOTE: All tests run in this JVM: [SpatialRPTFieldTypeTest, OpenExchangeRatesOrgProviderTest, TestSkipOverseerOperations, PeerSyncWithIndexFingerprintCachingTest, CacheHeaderTest, BasicFunctionalityTest, TestSolrCloudSnapshots, TestImpersonationWithHadoopAuth]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestImpersonationWithHadoopAuth -Dtests.seed=4FA08EB5C8EE72BA -Dtests.slow=true -Dtests.locale=cs-CZ -Dtests.timezone=Cuba -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | TestImpersonationWithHadoopAuth (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
[junit4] > 1) Thread[id=897, name=jetty-launcher-183-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > 2) Thread[id=892, name=jetty-launcher-183-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > at __randomizedtesting.SeedInfo.seed([4FA08EB5C8EE72BA]:0)
[junit4] Completed [24/764 (1!)] on J1 in 43.46s, 10 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 50536 lines...]
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 363 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/363/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData
Error Message:
Node watch should have fired!
Stack Trace:
java.lang.AssertionError: Node watch should have fired!
at __randomizedtesting.SeedInfo.seed([15D1B96701A56790:33415635552BA19A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13570 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestDistribStateManager_15D1B96701A56790-001/init-core-data-001
[junit4] 2> 4338492 WARN (SUITE-TestDistribStateManager-seed#[15D1B96701A56790]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 4338493 INFO (SUITE-TestDistribStateManager-seed#[15D1B96701A56790]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4338495 INFO (SUITE-TestDistribStateManager-seed#[15D1B96701A56790]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 4338496 INFO (SUITE-TestDistribStateManager-seed#[15D1B96701A56790]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 4338510 INFO (TEST-TestDistribStateManager.testHasData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHasData
[junit4] 2> 4338510 INFO (TEST-TestDistribStateManager.testHasData-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 4338510 INFO (TEST-TestDistribStateManager.testHasData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHasData
[junit4] 2> 4338522 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateMode
[junit4] 2> 4338523 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4338523 INFO (Thread-9294) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4338523 INFO (Thread-9294) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4338528 ERROR (Thread-9294) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4338623 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer start zk server on port:43175
[junit4] 2> 4338635 INFO (zkConnectionManagerCallback-7552-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4338636 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4338667 INFO (zkConnectionManagerCallback-7554-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4338668 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4338673 ERROR (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4338676 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43175 43175
[junit4] 2> 4338990 INFO (Thread-9294) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43175 43175
[junit4] 2> 4338998 INFO (TEST-TestDistribStateManager.testCreateMode-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateMode
[junit4] 2> 4339015 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMulti
[junit4] 2> 4339015 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4339016 INFO (Thread-9295) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4339016 INFO (Thread-9295) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4339022 ERROR (Thread-9295) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4339122 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer start zk server on port:58950
[junit4] 2> 4339134 INFO (zkConnectionManagerCallback-7556-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4339135 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4339137 ERROR (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4339142 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58950 58950
[junit4] 2> 4345787 INFO (Thread-9295) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58950 58950
[junit4] 2> 4345796 INFO (TEST-TestDistribStateManager.testMulti-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMulti
[junit4] 2> 4345809 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetSetRemoveData
[junit4] 2> 4345809 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4345810 INFO (Thread-9296) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4345810 INFO (Thread-9296) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4345814 ERROR (Thread-9296) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4345910 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer start zk server on port:52776
[junit4] 2> 4345935 INFO (zkConnectionManagerCallback-7558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4345939 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4345955 INFO (zkConnectionManagerCallback-7560-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4345956 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 4350957 ERROR (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4350961 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52776 52776
[junit4] 2> 4361121 INFO (Thread-9296) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52776 52776
[junit4] 2> 4361130 INFO (TEST-TestDistribStateManager.testGetSetRemoveData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetSetRemoveData
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistribStateManager -Dtests.method=testGetSetRemoveData -Dtests.seed=15D1B96701A56790 -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=AST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 15.3s J1 | TestDistribStateManager.testGetSetRemoveData <<<
[junit4] > Throwable #1: java.lang.AssertionError: Node watch should have fired!
[junit4] > at __randomizedtesting.SeedInfo.seed([15D1B96701A56790:33415635552BA19A]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4361146 INFO (TEST-TestDistribStateManager.testListData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListData
[junit4] 2> 4361146 INFO (TEST-TestDistribStateManager.testListData-seed#[15D1B96701A56790]) [ ] o.a.s.c.a.s.TestDistribStateManager Using org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 4361147 INFO (TEST-TestDistribStateManager.testListData-seed#[15D1B96701A56790]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListData
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestDistribStateManager_15D1B96701A56790-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {}, locale=uk, timezone=AST
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=81168016,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestAnalyzedSuggestions, TestSubQueryTransformerCrossCore, OpenCloseCoreStressTest, ZkFailoverTest, TestMaxTokenLenTokenizer, TestPostingsSolrHighlighter, OverseerCollectionConfigSetProcessorTest, TestBlobHandler, SmileWriterTest, TestPullReplica, TestStressCloudBlindAtomicUpdates, TestDocBasedVersionConstraints, SparseHLLTest, TestReloadDeadlock, IndexSchemaRuntimeFieldTest, TestSolr4Spatial2, DistributedFacetPivotLongTailTest, TestSolrConfigHandlerConcurrent, ExplicitHLLTest, DocExpirationUpdateProcessorFactoryTest, LeaderElectionIntegrationTest, AtomicUpdateProcessorFactoryTest, PreAnalyzedFieldTest, ExitableDirectoryReaderTest, InfoHandlerTest, TestStreamBody, DocValuesNotIndexedTest, TestLRUCache, SolrIndexConfigTest, RuleEngineTest, UpdateRequestProcessorFactoryTest, SystemInfoHandlerTest, TriLevelCompositeIdRoutingTest, BlockCacheTest, AutoscalingHistoryHandlerTest, SchemaVersionSpecificBehaviorTest, TestCollationFieldDocValues, TestLuceneMatchVersion, TestLockTree, TestDynamicLoading, DeleteStatusTest, TestCloudRecovery, TermVectorComponentDistributedTest, EchoParamsTest, TestNRTOpen, TestSchemaVersionResource, InfixSuggestersTest, TestSolrCloudSnapshots, TestFieldCache, TestUtilizeNode, SoftAutoCommitTest, HdfsAutoAddReplicasIntegrationTest, TestSort, TestSchemaResource, TestCoreBackup, CachingDirectoryFactoryTest, SolrJmxReporterTest, VersionInfoTest, CollectionStateFormat2Test, BasicDistributedZk2Test, HdfsSyncSliceTest, TestRebalanceLeaders, FileBasedSpellCheckerTest, TestManagedSynonymFilterFactory, TestEmbeddedSolrServerConstructors, TestClassicSimilarityFactory, CdcrUpdateLogTest, TestCopyFieldCollectionResource, CdcrVersionReplicationTest, TriggerIntegrationTest, TestSimDistributedQueue, TestCloudInspectUtil, TestManagedSchemaThreadSafety, SpellCheckCollatorWithCollapseTest, TestSearchPerf, TestSuggestSpellingConverter, TestHttpShardHandlerFactory, TestCSVLoader, SuggestComponentContextFilterQueryTest, TestRawResponseWriter, TestMacros, TestMultiWordSynonyms, PolyFieldTest, MoreLikeThisHandlerTest, TestInitQParser, TestStressLucene, TestDistribIDF, TestDistributedMissingSort, PeerSyncWithIndexFingerprintCachingTest, TestRestoreCore, TestComponentsName, TestComputePlanAction, TestRTGBase, BlockJoinFacetRandomTest, TestBM25SimilarityFactory, CloudMLTQParserTest, V2ApiIntegrationTest, TestFoldingMultitermQuery, TestSolrFieldCacheBean, MoveReplicaHDFSTest, CdcrBootstrapTest, TestRestManager, CustomCollectionTest, TestNestedDocsSort, TestShortCircuitedRequests, TestRTimerTree, TestCryptoKeys, HttpPartitionTest, HdfsTlogReplayBufferedWhileIndexingTest, TestNodeLostTrigger, TestSSLRandomization, NodeLostTriggerTest, CollectionsAPIAsyncDistributedZkTest, TestPayloadScoreQParserPlugin, TestSolrCloudWithKerberosAlt, DistributedDebugComponentTest, TestFastWriter, DeleteLastCustomShardedReplicaTest, TestShardHandlerFactory, TestHdfsUpdateLog, TestComplexPhraseQParserPlugin, SpatialRPTFieldTypeTest, TestFunctionQuery, DistributedQueryComponentCustomSortTest, AliasIntegrationTest, ExternalFileFieldSortTest, DateRangeFieldTest, TestTrackingShardHandlerFactory, MergeStrategyTest, TestCorePropertiesReload, TestRandomCollapseQParserPlugin, LegacyCloudClusterPropTest, SolrIndexSplitterTest, CoreSorterTest, ComputePlanActionTest, LoggingHandlerTest, TestCollationField, TestNumericTerms64, SolrCoreCheckLockOnStartupTest, TestSystemIdResolver, TestSolrConfigHandler, DistributedSuggestComponentTest, ChaosMonkeySafeLeaderTest, LeaderFailoverAfterPartitionTest, SimplePostToolTest, EnumFieldTest, TestSQLHandlerNonCloud, TestSchemaManager, BlobRepositoryCloudTest, ReplaceNodeTest, HdfsLockFactoryTest, SearchHandlerTest, TestClusterStateMutator, TestDistribDocBasedVersion, TestNumericRangeQuery64, MetricsConfigTest, TestSolrIndexConfig, TestSolrQueryParser, TestInPlaceUpdatesStandalone, SpellPossibilityIteratorTest, TestConfigSetProperties, TestImpersonationWithHadoopAuth, TestCloudManagedSchema, PKIAuthenticationIntegrationTest, TolerantUpdateProcessorTest, OverseerRolesTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, RecoveryAfterSoftCommitTest, CoreAdminHandlerTest, DocumentAnalysisRequestHandlerTest, HdfsNNFailoverTest, TestStressUserVersions, TestStressReorder, TestConfigSetsAPIExclusivity, TestManagedResource, ZkSolrClientTest, TestExactStatsCache, TestCustomDocTransformer, PreAnalyzedFieldManagedSchemaCloudTest, TestClusterProperties, TestZkChroot, QueryEqualityTest, TestGraphTermsQParserPlugin, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, TestRandomDVFaceting, ShardRoutingCustomTest, TestDistributedSearch, TestDistributedGrouping, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestJoin, TestReload, TestStressVersions, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, SpellCheckComponentTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, SolrRequestParserTest, DocValuesTest, TestTrie, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoBeanTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, TestQueryTypes, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TestValueSourceCache, TermVectorComponentTest, SolrPluginUtilsTest, TestJmxIntegration, MBeansHandlerTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, SOLR749Test, UpdateParamsTest, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, MultiTermTest, SampleTest, TestDocSet, TestBinaryField, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestSolrCoreProperties, DirectSolrConnectionTest, TestPhraseSuggestions, TestCharFilters, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, TestSolrJ, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest, CursorPagingTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestTolerantSearch, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, AssignTest, ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteReplicaTest, DistribJoinFromCollectionTest, DistributedQueueTest, LeaderElectionContextKeyTest, LeaderInitiatedRecoveryOnCommitTest, MissingSegmentRecoveryTest, MoveReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudPivotFacet, TestCloudPseudoReturnFields, TestCollectionsAPIViaSolrCloudCluster, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, TestLocalFSCloudBackupRestore, TestOnReconnectListenerSupport, TestRandomFlRTGCloud, TestRandomRequestDistribution, TestRequestForwarding, TestSegmentSorting, TestSolrCloudWithSecureImpersonation, TestStressInPlaceUpdates, MetricTriggerTest, NodeAddedTriggerTest, TestClusterStateProvider, TestDistribStateManager]
[junit4] Completed [590/764 (1!)] on J1 in 22.69s, 5 tests, 1 failure <<< FAILURES!
[...truncated 48768 lines...]