Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/580/
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration
Error Message:
events: [CapturedEvent{timestamp=14587741847822629, stage=STARTED,
actionName='null', event={ "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742021353879,
stage=BEFORE_ACTION, actionName='compute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742101558229,
stage=AFTER_ACTION, actionName='compute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742106488979,
stage=BEFORE_ACTION, actionName='execute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742111553229,
stage=AFTER_ACTION, actionName='execute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742119716829, stage=SUCCEEDED,
actionName='null', event={ "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3", "eventTime":14587736542437429,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747655910979, stage=STARTED,
actionName='null', event={ "id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747665662529,
stage=BEFORE_ACTION, actionName='compute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747680049929,
stage=AFTER_ACTION, actionName='compute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747683133479,
stage=BEFORE_ACTION, actionName='execute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747687543879,
stage=AFTER_ACTION, actionName='execute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection", "second":"shard1"}, {
"first":"testMergeIntegration_collection", "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan,
execute_plan]}, config={ "trigger":"index_size_trigger3", "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED"], "beforeAction":[
"compute_plan", "execute_plan"], "afterAction":[ "compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747694801479, stage=SUCCEEDED,
actionName='null', event={ "id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3", "eventTime":14587741706172329,
"eventType":"INDEXSIZE", "properties":{ "__start__":1,
"aboveSize":{}, "belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779, "requestedOps":[{
"action":"MERGESHARDS", "hints":{"COLL_SHARD":[ {
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3", "stage":[ "STARTED", "ABORTED",
"SUCCEEDED", "FAILED"], "beforeAction":[ "compute_plan",
"execute_plan"], "afterAction":[ "compute_plan", "execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}] expected:<6> but was:<12>
Stack Trace:
java.lang.AssertionError: events: [CapturedEvent{timestamp=14587741847822629,
stage=STARTED, actionName='null', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742021353879,
stage=BEFORE_ACTION, actionName='compute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742101558229,
stage=AFTER_ACTION, actionName='compute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742106488979,
stage=BEFORE_ACTION, actionName='execute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742111553229,
stage=AFTER_ACTION, actionName='execute_plan', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587742119716829, stage=SUCCEEDED,
actionName='null', event={
"id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
"source":"index_size_trigger3",
"eventTime":14587736542437429,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587741825195029,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747655910979, stage=STARTED,
actionName='null', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747665662529,
stage=BEFORE_ACTION, actionName='compute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747680049929,
stage=AFTER_ACTION, actionName='compute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747683133479,
stage=BEFORE_ACTION, actionName='execute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747687543879,
stage=AFTER_ACTION, actionName='execute_plan', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
"first":"testMergeIntegration_collection",
"second":"shard1"}, {
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan,
execute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}, CapturedEvent{timestamp=14587747694801479, stage=SUCCEEDED,
actionName='null', event={
"id":"33d3794a78b7a9T3wopmdym9ur6njsre1eo8azku",
"source":"index_size_trigger3",
"eventTime":14587741706172329,
"eventType":"INDEXSIZE",
"properties":{
"__start__":1,
"aboveSize":{},
"belowSize":{
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
"_enqueue_time_":14587747651673779,
"requestedOps":[{
"action":"MERGESHARDS",
"hints":{"COLL_SHARD":[
{
"first":"testMergeIntegration_collection",
"second":"shard1"},
{
"first":"testMergeIntegration_collection",
"second":"shard2"}]}}]}}, context={}, config={
"trigger":"index_size_trigger3",
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED"],
"beforeAction":[
"compute_plan",
"execute_plan"],
"afterAction":[
"compute_plan",
"execute_plan"],
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}] expected:<6> but was:<12>
at
__randomizedtesting.SeedInfo.seed([42C689D41FB93858:117FCB64FDA8ADA2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at
org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration(IndexSizeTriggerTest.java:463)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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
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:370)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 14171 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] 2> 1201871 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1201883 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_42C689D41FB93858-001/data-dir-180-001
[junit4] 2> 1201883 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1201884 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1201884 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_42C689D41FB93858-001/tempDir-001
[junit4] 2> 1201885 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1201890 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1201890 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1202177 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:45101
[junit4] 2> 1202177 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45101
[junit4] 2> 1202177 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45101
[junit4] 2> 1202177 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 45101
[junit4] 2> 1202206 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202236 INFO (zkConnectionManagerCallback-5582-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202236 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202288 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202289 INFO (zkConnectionManagerCallback-5584-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202289 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202290 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202302 INFO (zkConnectionManagerCallback-5586-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202302 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202414 WARN (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 1202414 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1202414 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1202415 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1202418 WARN (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 1202419 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1202419 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1202419 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1202629 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1202629 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1202629 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1202630 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1202630 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1202630 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@758de3bf{/solr,null,AVAILABLE}
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@33facfc5{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:41433}
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.e.j.s.Server Started @1202664ms
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41433}
[junit4] 2> 1202638 ERROR (jetty-launcher-5587-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1202638 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-01-04T07:51:36.171912Z
[junit4] 2> 1202640 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1bb51d88{/solr,null,AVAILABLE}
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3f0b5913{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:42961}
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.e.j.s.Server Started @1202667ms
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42961}
[junit4] 2> 1202641 ERROR (jetty-launcher-5587-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1202641 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-01-04T07:51:36.174438Z
[junit4] 2> 1202642 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202650 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202654 INFO (zkConnectionManagerCallback-5591-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202654 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202654 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1202658 INFO (zkConnectionManagerCallback-5589-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202658 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202659 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1202875 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1202876 WARN (jetty-launcher-5587-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@410325b1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202876 WARN (jetty-launcher-5587-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@410325b1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202878 WARN (jetty-launcher-5587-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@2211027[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202878 WARN (jetty-launcher-5587-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@2211027[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202879 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45101/solr
[junit4] 2> 1202879 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202880 INFO (zkConnectionManagerCallback-5599-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202880 INFO (jetty-launcher-5587-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202980 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1202982 WARN (jetty-launcher-5587-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@79793eb0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202982 WARN (jetty-launcher-5587-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@79793eb0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1202991 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1203128 INFO (zkConnectionManagerCallback-5602-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203128 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 1203129 WARN (jetty-launcher-5587-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@4794bf26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203129 WARN (jetty-launcher-5587-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@4794bf26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203130 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45101/solr
[junit4] 2> 1203131 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1203132 INFO (zkConnectionManagerCallback-5607-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203132 INFO (jetty-launcher-5587-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1203236 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1203236 INFO (zkConnectionManagerCallback-5611-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203236 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 1203246 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:41433_solr
[junit4] 2> 1203247 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.Overseer Overseer
(id=73010873400164360-127.0.0.1:41433_solr-n_0000000000) starting
[junit4] 2> 1203277 INFO
(OverseerStateUpdate-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[n:127.0.0.1:41433_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:41433_solr
[junit4] 2> 1203292 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41433_solr
[junit4] 2> 1203294 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1203299 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with
znode version 1
[junit4] 2> 1203300 INFO (zkCallback-5610-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1203300 WARN (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 1203305 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 0
[junit4] 2> 1203306 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1203323 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:41433_solr]
[junit4] 2> 1203323 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1203331 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 1203334 DEBUG
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1203335 DEBUG (ScheduledTrigger-5426-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1203335 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1203337 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:42961_solr as DOWN
[junit4] 2> 1203338 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1203338 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42961_solr
[junit4] 2> 1203338 INFO (zkCallback-5610-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1203339 WARN (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 1203351 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41433.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203362 INFO (zkCallback-5601-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1203366 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41433.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203372 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41433.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203378 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1203382 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 1203383 INFO (jetty-launcher-5587-thread-2)
[n:127.0.0.1:41433_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_42C689D41FB93858-001/tempDir-001/node2/.
[junit4] 2> 1203391 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42961.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203555 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42961.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203555 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42961.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2f0cf3e6
[junit4] 2> 1203556 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 1203557 INFO (jetty-launcher-5587-thread-1)
[n:127.0.0.1:42961_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_42C689D41FB93858-001/tempDir-001/node1/.
[junit4] 2> 1203586 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1203588 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1203595 INFO (zkConnectionManagerCallback-5622-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203595 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1203598 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1203601 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45101/solr ready
[junit4] 2> 1203626 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1203626 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1203627 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1203627 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
1
[junit4] 2> 1203627 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: []
[junit4] 2> 1203628 DEBUG
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10524_solr
[junit4] 2> 1203630 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1203630 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1203631 DEBUG (ScheduledTrigger-5439-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1 and last live nodes: 0
[junit4] 2> 1203638 DEBUG
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=0
[junit4] 2> 1203638 DEBUG
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 1203638 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 1203653 DEBUG (ScheduledTrigger-5439-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1203674 DEBUG (ScheduledTrigger-5439-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203694 DEBUG (ScheduledTrigger-5439-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203714 DEBUG (ScheduledTrigger-5439-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203740 DEBUG (ScheduledTrigger-5439-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203761 DEBUG (ScheduledTrigger-5439-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203781 DEBUG (ScheduledTrigger-5439-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203801 DEBUG (ScheduledTrigger-5439-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203821 DEBUG (ScheduledTrigger-5439-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203841 DEBUG (ScheduledTrigger-5439-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203937 DEBUG (ScheduledTrigger-5439-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203940 INFO
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.SolrTestCaseJ4 ###Starting testInvalidSplitConfig
[junit4] 2> 1203945 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=1
[junit4] 2> 1203945 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 1203945 INFO
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 2
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## Max replicas per node: 0
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 0
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 1203945 INFO
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and
clearing object cache...
[junit4] 2> 1203945 INFO (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1203946 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 1203948 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1203949 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1203949 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
1
[junit4] 2> 1203950 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10525_solr, 127.0.0.1:10524_solr]
[junit4] 2> 1203950 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1203950 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1203951 DEBUG (ScheduledTrigger-5444-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1203957 DEBUG
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 2
[junit4] 2> 1203957 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
2
[junit4] 2> 1203957 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1203957 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1204058 INFO
(TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[42C689D41FB93858]) [
] o.a.s.SolrTestCaseJ4 ###Ending testInvalidSplitConfig
[junit4] 2> 1204079 INFO
(TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[42C689D41FB93858]) [
] o.a.s.SolrTestCaseJ4 ###Starting testMergeIntegration
[junit4] 2> 1204080 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection
testMergeIntegration_collection, currentVersion=2
[junit4] 2> 1204080 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=2
[junit4] 2> 1204080 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 1204090 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=3
[junit4] 2> 1204100 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 1204116 DEBUG (simCloudManagerPool-5443-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=4
[junit4] 2> 1204116 DEBUG (simCloudManagerPool-5443-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 1204116 DEBUG (simCloudManagerPool-5443-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election
(testMergeIntegration_collection / shard1)
[junit4] 2> 1204116 DEBUG (simCloudManagerPool-5443-thread-2) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testMergeIntegration_collection / shard1 (currentVersion=5): {"core_node1":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testMergeIntegration_collection",
[junit4] 2> "node_name":"127.0.0.1:10524_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1204127 DEBUG (simCloudManagerPool-5443-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=5
[junit4] 2> 1204127 DEBUG (simCloudManagerPool-5443-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 1204137 DEBUG (simCloudManagerPool-5443-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=6
[junit4] 2> 1204137 DEBUG (simCloudManagerPool-5443-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 1204137 DEBUG (simCloudManagerPool-5443-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election
(testMergeIntegration_collection / shard2)
[junit4] 2> 1204137 DEBUG (simCloudManagerPool-5443-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
testMergeIntegration_collection / shard2 (currentVersion=7): {"core_node3":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testMergeIntegration_collection",
[junit4] 2> "node_name":"127.0.0.1:10524_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1204137 DEBUG (simCloudManagerPool-5443-thread-1) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection
testMergeIntegration_collection, currentVersion=7
[junit4] 2> 1204148 DEBUG (simCloudManagerPool-5443-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=7
[junit4] 2> 1204148 DEBUG (simCloudManagerPool-5443-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 1204148 DEBUG
(TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[42C689D41FB93858]) [
] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states,
currentVersion=8
[junit4] 2> 1204148 DEBUG
(TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[42C689D41FB93858]) [
] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 1204171 DEBUG (simCloudManagerPool-5443-thread-27) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1204171 DEBUG (simCloudManagerPool-5443-thread-27) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1204171 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
3
[junit4] 2> 1204171 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1204171 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1204174 DEBUG (simCloudManagerPool-5443-thread-28) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1204174 DEBUG (simCloudManagerPool-5443-thread-28) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 1204175 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
4
[junit4] 2> 1204175 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1204175 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 1204176 DEBUG (simCloudManagerPool-5443-thread-29) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1204177 DEBUG (simCloudManagerPool-5443-thread-29) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 1204178 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
5
[junit4] 2> 1204178 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1204178 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 1204223 DEBUG (simCloudManagerPool-5443-thread-46) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1204223 DEBUG (simCloudManagerPool-5443-thread-46) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 1204223 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion
6
[junit4] 2> 1204224 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded
markers
[junit4] 2> 1204224 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 1204331 DEBUG (ScheduledTrigger-5444-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 1204331 DEBUG (ScheduledTrigger-5444-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger3]
[junit4] 2> 1204331 DEBUG (ScheduledTrigger-5444-thread-1) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 1204332 INFO (ScheduledTrigger-5444-thread-1) [ ]
o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=14587741847822629, stage=STARTED, actionName='null',
event={
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 1204335 DEBUG (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 1204335 INFO (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=14587742021353879, stage=BEFORE_ACTION,
actionName='compute_plan', event={
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}},
context={properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger3},
config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 1204335 DEBUG (ScheduledTrigger-5426-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1204335 DEBUG (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}} with context
properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 1204336 INFO (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.ComputePlanAction Computed plan empty, remained 0 requested ops to
try.
[junit4] 2> 1204337 INFO (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=14587742101558229, stage=AFTER_ACTION,
actionName='compute_plan', event={
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger3,
properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 1204337 INFO (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.IndexSizeTriggerTest =======>
CapturedEvent{timestamp=14587742106488979, stage=BEFORE_ACTION,
actionName='execute_plan', event={
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":14587736542437429,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2>
"testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2>
"testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":14587741825195029,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}},
context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}],
properties.BEFORE_ACTION=[compute_plan, execute_plan],
source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 1204337 DEBUG (AutoscalingActionExecutor-5445-thread-1) [
] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"33d37816b06035T3wopmdym9ur6njsre1eo8azkt",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":145877365
[...truncated too long message...]
for registry=solr.cluster, tag=null
[junit4] 2> 1207314 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1207323 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1207323 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6dd5ec56: rootName =
solr_41433, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@47cd8ae7
[junit4] 2> 1207327 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1207327 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@48b03956: rootName =
solr_41433, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7f030f9e
[junit4] 2> 1207331 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1207331 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2d1418ff: rootName =
solr_41433, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@68987e02
[junit4] 2> 1207331 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1207337 INFO (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73010873400164360-127.0.0.1:41433_solr-n_0000000000) closing
[junit4] 2> 1207338 INFO
(OverseerStateUpdate-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[n:127.0.0.1:41433_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:41433_solr
[junit4] 2> 1207338 INFO
(OverseerAutoScalingTriggerThread-73010873400164360-127.0.0.1:41433_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1207338 DEBUG (ScheduledTrigger-5426-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1207338 INFO (zkCallback-5610-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1207340 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 1207340 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 1207341 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 1207341 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 1207341 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1207341 DEBUG (closeThreadPool-5631-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1207341 INFO (closeThreadPool-5631-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=73010873400164360-127.0.0.1:41433_solr-n_0000000000) closing
[junit4] 2> 1207433 INFO (jetty-closer-5623-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@3f0b5913{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 1207433 INFO (jetty-closer-5623-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1bb51d88{/solr,null,UNAVAILABLE}
[junit4] 2> 1207433 INFO (jetty-closer-5623-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1207441 INFO (jetty-closer-5623-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73010873400164360-127.0.0.1:41433_solr-n_0000000000) closing
[junit4] 2> 1207442 INFO (jetty-closer-5623-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@33facfc5{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 1207442 INFO (jetty-closer-5623-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@758de3bf{/solr,null,UNAVAILABLE}
[junit4] 2> 1207442 INFO (jetty-closer-5623-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1207443 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1207645 WARN (ZkTestServer Run Thread) [ ]
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/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 1207646 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45101
[junit4] 2> 1207646 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45101
[junit4] 2> 1207646 INFO
(SUITE-IndexSizeTriggerTest-seed#[42C689D41FB93858]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 45101
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_42C689D41FB93858-001
[junit4] 2> Jan 04, 2020 7:51:41 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {},
docValues:{}, maxPointsInLeafNode=49, maxMBSortInHeap=7.172072039286272,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6fae65e0),
locale=es-NI, timezone=Pacific/Nauru
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4
(64-bit)/cpus=4,threads=1,free=352950720,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestBulkSchemaConcurrent,
TestRTimerTree, TestQueryTypes, StatsComponentTest,
OverseerCollectionConfigSetProcessorTest, TestFieldCacheWithThreads,
SolrLogPostToolTest, PluginInfoTest, CoreMergeIndexesAdminHandlerTest,
CustomTermsComponentTest, TestReloadAndDeleteDocs, TestDeprecatedFilters,
RegexBoostProcessorTest, DirectoryFactoryTest, SuggesterWFSTTest,
TestConfigSets, TestManagedResource, DistanceUnitsTest, TestBulkSchemaAPI,
TestAuthorizationFramework, TestSolrCloudWithHadoopAuthPlugin,
TestFieldTypeCollectionResource, TestBinaryResponseWriter,
CollectionsAPIAsyncDistributedZkTest, BlobRepositoryCloudTest,
CloudExitableDirectoryReaderTest, TestSimLargeCluster,
TestCloudSearcherWarming, TestSimExecutePlanAction, TestSolrQueryParser,
ChaosMonkeySafeLeaderWithPullReplicasTest, ReplaceNodeTest,
ChaosMonkeyNothingIsSafeTest, ClassificationUpdateProcessorFactoryTest,
QueryElevationComponentTest, AnalysisErrorHandlingTest, ZkStateReaderTest,
AdminHandlersProxyTest, SpatialRPTFieldTypeTest, TestFoldingMultitermQuery,
AddReplicaTest, NestedAtomicUpdateTest, JavabinLoaderTest,
TestSubQueryTransformer, WrapperMergePolicyFactoryTest,
TestStressInPlaceUpdates, TestSolrJacksonAnnotation, SuggesterFSTTest,
TestDeleteCollectionOnDownNodes, DirectSolrSpellCheckerTest,
UpdateRequestProcessorFactoryTest, DistributedExpandComponentTest,
TestCaffeineCache, TestSimUtils, BadIndexSchemaTest, SolrMetricReporterTest,
TestCollationField, TestConfigSetsAPIExclusivity, CollectionPropsTest,
JWTAuthPluginTest, MultiDestinationAuditLoggerTest, PeerSyncReplicationTest,
TestUnifiedSolrHighlighter, TestLocalStatsCacheCloud,
SearchRateTriggerIntegrationTest, TestDistributedStatsComponentCardinality,
TestZkChroot, BasicDistributedZk2Test, TestJettySolrRunner,
CdcrBidirectionalTest, BlockCacheTest, TestDistribPackageStore,
TestLMJelinekMercerSimilarityFactory, TestLocalFSCloudBackupRestore,
Tagger2Test, DistanceFunctionTest, URLClassifyProcessorTest,
SoftAutoCommitTest, SearchHandlerTest, TestSchemaManager,
OverseerSolrResponseTest, SolrCloudExampleTest, XmlInterpolationTest,
AutoAddReplicasIntegrationTest, TestPolicyCloud, CircularListTest,
NodeLostTriggerTest, ZkControllerTest, TestSQLHandlerNonCloud,
TestSolr4Spatial, SolrCloudReportersTest, SchemaApiFailureTest,
TestUtilizeNode, TestComplexPhraseQParserPlugin, TemplateUpdateProcessorTest,
TestIntervalFaceting, TestSolrConfigHandler, TestExceedMaxTermLength,
TransactionLogTest, AuditLoggerIntegrationTest, TestPayloadCheckQParserPlugin,
TestCloudPseudoReturnFields, TestFastOutputStream,
DistributedFacetPivotWhiteBoxTest, PathHierarchyTokenizerFactoryTest,
TestWordDelimiterFilterFactory, HdfsDirectoryTest, TestFieldSortValues,
BasicAuthOnSingleNodeTest, TestJsonFacetsWithNestedObjects,
SubstringBytesRefFilterTest, TestDownShardTolerantSearch,
BasicFunctionalityTest, PropertiesRequestHandlerTest, TestStressLucene,
IndexSizeTriggerTest]
[junit4] Completed [417/897 (1!)] on J1 in 6.46s, 6 tests, 1 failure <<<
FAILURES!
[...truncated 54651 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
