-
Bug
-
Resolution: Done
-
Major
-
None
-
1.5.0.GA
-
None
Back link to Report Portal:
Test execution log:
Test execution log
Time: 05/24/2020 13:52:38, Level: INFO, Log: 2020-05-24 08:54:39 [32mINFO [m [TimeMeasuringSystem:94] Start time of operation TEST_EXECUTION is correctly stored 2020-05-24 08:54:39 [32mINFO [m [TestSeparator:27] ############################################################################ 2020-05-24 08:54:39 [32mINFO [m [TestSeparator:28] io.strimzi.systemtest.tracing.TracingST.testKafkaBridgeService-STARTED 2020-05-24 08:54:39 [32mINFO [m [ResourceManager:100] Setting pointer to method resources 2020-05-24 08:54:39 [32mINFO [m [TracingST:828] === Applying jaeger operator install files === 2020-05-24 08:54:39 [32mINFO [m [TracingST:834] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-operator/jaegertracing.io_jaegers_crd.yaml 2020-05-24 08:54:40 [32mINFO [m [TracingST:834] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-operator/operator.yaml 2020-05-24 08:54:40 [32mINFO [m [TracingST:834] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-operator/role.yaml 2020-05-24 08:54:41 [32mINFO [m [TracingST:834] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-operator/role_binding.yaml 2020-05-24 08:54:42 [32mINFO [m [TracingST:834] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-operator/service_account.yaml 2020-05-24 08:54:42 [32mINFO [m [TracingST:866] === Applying jaeger instance install files === 2020-05-24 08:54:42 [32mINFO [m [TracingST:872] Applying configuration file: ../systemtest/src/test/resources/tracing/jaeger-instance/jaeger.yaml 2020-05-24 08:54:43 [32mINFO [m [TracingST:859] Network policy for jaeger successfully applied 2020-05-24 08:54:47 [32mINFO [m [DeploymentUtils:173] Waiting for 1 Pod(s) of Deployment my-cluster-kafka-clients to be ready 2020-05-24 08:54:59 [32mINFO [m [DeploymentUtils:176] Deployment my-cluster-kafka-clients is ready 2020-05-24 08:54:59 [32mINFO [m [KafkaResource:220] Waiting for Kafka my-cluster in namespace tracing-cluster-test 2020-05-24 08:54:59 [32mINFO [m [StatefulSetUtils:137] Waiting for StatefulSet my-cluster-zookeeper to be ready 2020-05-24 08:55:01 [32mINFO [m [StatefulSetUtils:142] Waiting for 1 Pod(s) of StatefulSet my-cluster-zookeeper to be ready 2020-05-24 08:55:32 [32mINFO [m [StatefulSetUtils:145] StatefulSet my-cluster-zookeeper is ready 2020-05-24 08:55:32 [32mINFO [m [StatefulSetUtils:137] Waiting for StatefulSet my-cluster-kafka to be ready 2020-05-24 08:55:32 [32mINFO [m [StatefulSetUtils:142] Waiting for 3 Pod(s) of StatefulSet my-cluster-kafka to be ready 2020-05-24 08:56:05 [32mINFO [m [StatefulSetUtils:145] StatefulSet my-cluster-kafka is ready 2020-05-24 08:56:05 [32mINFO [m [DeploymentUtils:156] Waiting for Deployment my-cluster-entity-operator 2020-05-24 08:56:18 [32mINFO [m [DeploymentUtils:160] Deployment my-cluster-entity-operator is ready 2020-05-24 08:56:18 [32mINFO [m [ResourceManager:416] Wait for KafkaBridge: my-cluster will have desired state: Ready 2020-05-24 08:56:39 [32mINFO [m [ResourceManager:425] KafkaBridge:my-cluster is in desired state: Ready 2020-05-24 08:56:39 [32mINFO [m [KubernetesResource:262] Creating Service my-cluster-bridge-external-service in namespace tracing-cluster-test 2020-05-24 08:56:39 [32mINFO [m [ServiceUtils:59] Waiting for Service my-cluster-bridge-external-service in namespace tracing-cluster-test 2020-05-24 08:56:49 [32mINFO [m [ServiceUtils:65] Service my-cluster-bridge-external-service in namespace tracing-cluster-test is ready 2020-05-24 08:56:49 [32mINFO [m [KafkaTopicResource:65] Created KafkaTopic topic-simple-send 2020-05-24 08:56:49 [32mINFO [m [ResourceManager:416] Wait for KafkaTopic: topic-simple-send will have desired state: Ready 2020-05-24 08:56:50 [32mINFO [m [ResourceManager:425] KafkaTopic:topic-simple-send is in desired state: Ready 2020-05-24 08:56:50 [32mINFO [m [HttpUtils:81] Creating 100 records for KafkaBridge 2020-05-24 08:56:50 [32mINFO [m [HttpUtils:94] Sending records to KafkaBridge 2020-05-24 08:57:50 [32mINFO [m [TimeMeasuringSystem:113] End time of operation TEST_EXECUTION is correctly stored 2020-05-24 08:57:50 [32mINFO [m [LogCollector:64] Collecting events in Namespace tracing-cluster-test 2020-05-24 08:57:50 [1;31mERROR[m [HttpUtils:110] Server didn't accept post io.netty.channel.ConnectTimeoutException: connection timed out: /10.0.133.0:30754 at io.netty.channel.nio.AbstractNioChannel$AbstractNioUnsafe$1.run(AbstractNioChannel.java:261) [netty-transport-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.PromiseTask.runTask(PromiseTask.java:98) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.ScheduledFutureTask.run(ScheduledFutureTask.java:170) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:472) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:500) [netty-transport-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [netty-common-4.1.45.Final.jar:4.1.45.Final] at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [netty-common-4.1.45.Final.jar:4.1.45.Final] at java.lang.Thread.run(Thread.java:834) [?:?] 2020-05-24 08:57:51 [32mINFO [m [LogCollector:71] Collecting ConfigMaps in Namespace tracing-cluster-test 2020-05-24 08:57:51 [32mINFO [m [LogCollector:44] Collecting logs for Pod(s) in namespace tracing-cluster-test 2020-05-24 08:57:59 [32mINFO [m [LogCollector:78] Collecting Deployments in Namespace tracing-cluster-test 2020-05-24 08:57:59 [32mINFO [m [LogCollector:83] Collecting StatefulSets in Namespace tracing-cluster-test 2020-05-24 08:57:59 [32mINFO [m [LogCollector:88] Collecting ReplicaSet in Namespace tracing-cluster-test 2020-05-24 08:57:59 [32mINFO [m [LogCollector:93] Collecting Strimzi in Namespace tracing-cluster-test 2020-05-24 08:58:00 [32mINFO [m [Exec:167] Command: oc get strimzi -o yaml -n tracing-cluster-test 2020-05-24 08:58:00 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:00 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:00 [32mINFO [m [Exec:171] apiVersion: v1 items: - apiVersion: kafka.strimzi.io/v1beta1 kind: Kafka metadata: creationTimestamp: "2020-05-24T08:55:00Z" generation: 1 name: my-cluster namespace: tracing-cluster-test resourceVersion: "116980" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/tracing-cluster-test/kafkas/my-cluster uid: 519dbbb7-12b2-4c6d-8086-f74ba5cbe825 spec: entityOperator: topicOperator: image: docker.io/strimzi/operator:latest logging: loggers: rootLogger.level: DEBUG type: inline reconciliationIntervalSeconds: 90 topicMetadataMaxAttempts: 6 zookeeperSessionTimeoutSeconds: 20 userOperator: image: docker.io/strimzi/operator:latest logging: loggers: rootLogger.level: DEBUG type: inline reconciliationIntervalSeconds: 120 zookeeperSessionTimeoutSeconds: 6 kafka: config: log.message.format.version: "2.5" offsets.topic.replication.factor: 3 transaction.state.log.min.isr: 2 transaction.state.log.replication.factor: 3 listeners: external: tls: false type: nodeport plain: {} tls: {} logging: loggers: kafka.root.logger.level: DEBUG type: inline replicas: 3 storage: type: ephemeral version: 2.5.0 zookeeper: logging: loggers: zookeeper.root.logger: DEBUG type: inline replicas: 1 storage: type: ephemeral status: conditions: - lastTransitionTime: "2020-05-24T08:55:22.604Z" message: A ZooKeeper cluster with a single replica and ephemeral storage will be in a defective state after any restart or rolling update. It is recommended that a minimum of three replicas are used. reason: ZooKeeperStorage status: "True" type: Warning - lastTransitionTime: 2020-05-24T08:56:18+0000 status: "True" type: Ready listeners: - addresses: - host: my-cluster-kafka-bootstrap.tracing-cluster-test.svc port: 9092 bootstrapServers: my-cluster-kafka-bootstrap.tracing-cluster-test.svc:9092 type: plain - addresses: - host: my-cluster-kafka-bootstrap.tracing-cluster-test.svc port: 9093 bootstrapServers: my-cluster-kafka-bootstrap.tracing-cluster-test.svc:9093 certificates: - | -----BEGIN CERTIFICATE----- MIIDLTCCAhWgAwIBAgIJAPwtrPTmb5s5MA0GCSqGSIb3DQEBCwUAMC0xEzARBgNV BAoMCmlvLnN0cmltemkxFjAUBgNVBAMMDWNsdXN0ZXItY2EgdjAwHhcNMjAwNTI0 MDg1NTAwWhcNMjEwNTI0MDg1NTAwWjAtMRMwEQYDVQQKDAppby5zdHJpbXppMRYw FAYDVQQDDA1jbHVzdGVyLWNhIHYwMIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIB CgKCAQEAo/vkh2kHcUIufKzdGLPdqS+7MtJm+qz9Di6BWCHgu3igua7sK9pVTgFq 0AMjiYN9CiVuyh6E7Wz3c/RW3r4J9slcogJY2m87QAiEUDf/iWNAaSGxuKfFKcWY slRUtbNA+rBjEg8yAFLOfKJruiIx3K19vRcnaYHzluZtQqhITTXwkSawFED9dpqE XeGvpfgzdYn/t/CSd2Y1kO32lL5mip6XvGPIwEXhhEceeH6WhyP8CLwSE45VekcK HjCAkWqW/syQ96264Uyoa/W5UZREO/fwCx3HirvT7RzbhQFiXRdvqEnE/EZf/ixA Y20Ch8g0cQQJdUfgPCT00t4BNAj1awIDAQABo1AwTjAdBgNVHQ4EFgQUI3jro89O /AiX/xlTp60VurPxsXAwHwYDVR0jBBgwFoAUI3jro89O/AiX/xlTp60VurPxsXAw DAYDVR0TBAUwAwEB/zANBgkqhkiG9w0BAQsFAAOCAQEAcmx/ks43yRIoDfzqW39h XVNqWw+qvfOF15+BwymlQ8wL2vI9BFsgIQtw4yysvaMVHh8XgtXlHZPVkvRhU9a9 alRxA4fM9IqXpl8suLDvvdk0Lmb+8Dd6B+CpM2DHTzq+3g/1YT6iQNtrzP92F1Db fEaih4neS9prc8r55Z+M1iFu/cnnAiWPCMgEGJQawqILtawDuRUAojwVFo0Eidy7 Nu99S1H3iJamRF4b3wPqvANIW70zVj2EN29P3irBQrDFOAnlBGndF7kQv3nhJ3Zw RdzrhMMJGM7XZO8U8lxplh5XUHnGuz2B/Q2JjJ8YyV6gWJxyus6u8nnRQV+8HS7s sg== -----END CERTIFICATE----- type: tls - addresses: - host: ip-10-0-133-0.us-east-2.compute.internal port: 32716 - host: ip-10-0-171-218.us-east-2.compute.internal port: 32716 - host: ip-10-0-153-236.us-east-2.compute.internal port: 32716 bootstrapServers: ip-10-0-133-0.us-east-2.compute.internal:32716,ip-10-0-171-218.us-east-2.compute.internal:32716,ip-10-0-153-236.us-east-2.compute.internal:32716 type: external observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-05-24T08:48:22Z" generation: 2 labels: strimzi.io/cluster: my-cluster name: consumer-offsets---84e7a678d08f4bd226872e5cdd4eb527fadc1c6a namespace: tracing-cluster-test resourceVersion: "117470" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/tracing-cluster-test/kafkatopics/consumer-offsets---84e7a678d08f4bd226872e5cdd4eb527fadc1c6a uid: 9f4fee93-6868-4540-859b-26128394386e spec: config: cleanup.policy: compact compression.type: producer segment.bytes: "104857600" partitions: 50 replicas: 3 topicName: __consumer_offsets status: conditions: - lastTransitionTime: "2020-05-24T08:57:34.769Z" status: "True" type: Ready observedGeneration: 2 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-05-24T08:47:31Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: my-topic namespace: tracing-cluster-test resourceVersion: "113092" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/tracing-cluster-test/kafkatopics/my-topic uid: 62763b50-9d36-4e54-a826-84a2babb8604 spec: config: {} partitions: 1 replicas: 1 topicName: my-topic status: conditions: - lastTransitionTime: "2020-05-24T08:47:31.731Z" status: "True" type: Ready observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-05-24T08:56:49Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: topic-simple-send namespace: tracing-cluster-test resourceVersion: "117219" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/tracing-cluster-test/kafkatopics/topic-simple-send uid: f96eed7d-6470-4f46-9a27-a2c8137fb4a3 spec: config: min.insync.replicas: 1 retention.ms: 7200000 segment.bytes: 1073741824 partitions: 1 replicas: 1 status: conditions: - lastTransitionTime: "2020-05-24T08:56:49.824Z" status: "True" type: Ready observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1alpha1 kind: KafkaBridge metadata: creationTimestamp: "2020-05-24T08:56:18Z" generation: 1 name: my-cluster namespace: tracing-cluster-test resourceVersion: "117136" selfLink: /apis/kafka.strimzi.io/v1alpha1/namespaces/tracing-cluster-test/kafkabridges/my-cluster uid: 7d33e04d-44d2-4bc3-aa33-7085175fe7a3 spec: bootstrapServers: my-cluster-kafka-bootstrap:9092 http: port: 8080 logging: loggers: bridge.root.logger: DEBUG type: inline replicas: 1 template: bridgeContainer: env: - name: JAEGER_SERVICE_NAME value: my-kafka-bridge - name: JAEGER_AGENT_HOST value: my-jaeger-agent - name: JAEGER_SAMPLER_TYPE value: const - name: JAEGER_SAMPLER_PARAM value: "1" tracing: type: jaeger status: conditions: - lastTransitionTime: "2020-05-24T08:56:38.966Z" status: "True" type: Ready observedGeneration: 1 url: http://my-cluster-bridge-service.tracing-cluster-test.svc:8080 kind: List metadata: resourceVersion: "" selfLink: "" 2020-05-24 08:58:00 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:01 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:01 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:171] jaeger.jaegertracing.io "my-jaeger" deleted 2020-05-24 08:58:01 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:01 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:01 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:171] serviceaccount "jaeger-operator" deleted 2020-05-24 08:58:01 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:01 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:01 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:171] clusterrolebinding.rbac.authorization.k8s.io "jaeger-operator" deleted 2020-05-24 08:58:01 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:175] ======STDERR START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:176] warning: deleting cluster-scoped resources, not scoped to the provided namespace 2020-05-24 08:58:01 [32mINFO [m [Exec:177] ======STDERR END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:01 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:01 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:171] clusterrole.rbac.authorization.k8s.io "jaeger-operator" deleted 2020-05-24 08:58:01 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:01 [32mINFO [m [Exec:175] ======STDERR START======= 2020-05-24 08:58:01 [32mINFO [m [Exec:176] warning: deleting cluster-scoped resources, not scoped to the provided namespace 2020-05-24 08:58:01 [32mINFO [m [Exec:177] ======STDERR END====== 2020-05-24 08:58:02 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:02 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:02 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:02 [32mINFO [m [Exec:171] deployment.apps "jaeger-operator" deleted 2020-05-24 08:58:02 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:02 [32mINFO [m [Exec:167] Command: oc --namespace tracing-cluster-test delete -f - 2020-05-24 08:58:02 [32mINFO [m [Exec:168] RETURN code: 0 2020-05-24 08:58:02 [32mINFO [m [Exec:170] ======STDOUT START======= 2020-05-24 08:58:02 [32mINFO [m [Exec:171] customresourcedefinition.apiextensions.k8s.io "jaegers.jaegertracing.io" deleted 2020-05-24 08:58:02 [32mINFO [m [Exec:172] ======STDOUT END====== 2020-05-24 08:58:02 [32mINFO [m [Exec:175] ======STDERR START======= 2020-05-24 08:58:02 [32mINFO [m [Exec:176] warning: deleting cluster-scoped resources, not scoped to the provided namespace 2020-05-24 08:58:02 [32mINFO [m [Exec:177] ======STDERR END====== 2020-05-24 08:58:02 [32mINFO [m [TimeMeasuringSystem:113] End time of operation TEST_EXECUTION is correctly stored 2020-05-24 08:58:02 [32mINFO [m [BaseST:673] Search in strimzi-cluster-operator log for errors in last 202 seconds 2020-05-24 08:58:02 [32mINFO [m [BaseCmdKubeClient:380] Exception not found 2020-05-24 08:58:02 [32mINFO [m [BaseST:757] Test execution contains exception, going to recreate test environment 2020-05-24 08:58:02 [32mINFO [m [ResourceManager:353] -----CLEARING METHOD RESOURCES----- 2020-05-24 08:58:02 [32mINFO [m [ResourceManager:227] Deleting KafkaTopic topic-simple-send in namespace tracing-cluster-test 2020-05-24 08:58:02 [32mINFO [m [ResourceManager:209] Deleting Service my-cluster-bridge-external-service in namespace tracing-cluster-test 2020-05-24 08:58:02 [32mINFO [m [ResourceManager:177] Deleting KafkaBridge my-cluster in namespace tracing-cluster-test 2020-05-24 08:58:02 [32mINFO [m [ResourceManager:323] Waiting when all the Pods are terminated for KafkaBridge my-cluster 2020-05-24 08:58:03 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-bridge-74dc4c5b46-9t7hv will be deleted 2020-05-24 08:58:39 [32mINFO [m [PodUtils:164] Pod my-cluster-bridge-74dc4c5b46-9t7hv deleted 2020-05-24 08:58:39 [32mINFO [m [ResourceManager:122] Deleting Kafka my-cluster in namespace tracing-cluster-test 2020-05-24 08:58:39 [32mINFO [m [ResourceManager:240] Waiting when all the pods are terminated for Kafka my-cluster 2020-05-24 08:58:39 [33mWARN [m [StatefulSetUtils:159] StatefulSet my-cluster-zookeeper is not deleted yet! Triggering force delete by cmd client! 2020-05-24 08:58:44 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-zookeeper-0 will be deleted 2020-05-24 08:58:49 [32mINFO [m [PodUtils:164] Pod my-cluster-zookeeper-0 deleted 2020-05-24 08:58:49 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-kafka-0 will be deleted 2020-05-24 08:59:20 [32mINFO [m [PodUtils:164] Pod my-cluster-kafka-0 deleted 2020-05-24 08:59:20 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-kafka-1 will be deleted 2020-05-24 08:59:20 [32mINFO [m [PodUtils:164] Pod my-cluster-kafka-1 deleted 2020-05-24 08:59:20 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-kafka-2 will be deleted 2020-05-24 08:59:20 [32mINFO [m [PodUtils:164] Pod my-cluster-kafka-2 deleted 2020-05-24 08:59:20 [32mINFO [m [SecretUtils:94] Waiting for Secret my-cluster deletion 2020-05-24 08:59:20 [32mINFO [m [SecretUtils:108] Secret my-cluster deleted 2020-05-24 08:59:20 [32mINFO [m [PersistentVolumeClaimUtils:58] Wait until PVC deletion for cluster my-cluster 2020-05-24 08:59:20 [32mINFO [m [PersistentVolumeClaimUtils:72] PVC for cluster my-cluster was deleted 2020-05-24 08:59:20 [32mINFO [m [ConfigMapUtils:66] Waiting for all ConfigMaps of cluster my-cluster-kafka-config deletion 2020-05-24 08:59:20 [32mINFO [m [ConfigMapUtils:80] ConfigMaps of cluster my-cluster-kafka-config were deleted 2020-05-24 08:59:20 [32mINFO [m [ConfigMapUtils:66] Waiting for all ConfigMaps of cluster my-cluster-zookeeper-config deletion 2020-05-24 08:59:20 [32mINFO [m [ConfigMapUtils:80] ConfigMaps of cluster my-cluster-zookeeper-config were deleted 2020-05-24 08:59:20 [32mINFO [m [ResourceManager:188] Deleting Deployment my-cluster-kafka-clients 2020-05-24 08:59:20 [32mINFO [m [ResourceManager:334] Waiting when all the pods are terminated for Deployment my-cluster-kafka-clients 2020-05-24 08:59:20 [33mWARN [m [DeploymentUtils:190] Deployment my-cluster-kafka-clients is not deleted yet! Triggering force delete by cmd client! 2020-05-24 08:59:26 [32mINFO [m [PodUtils:147] Waiting when Pod my-cluster-kafka-clients-5d498854bf-96247 will be deleted 2020-05-24 09:00:01 [32mINFO [m [PodUtils:164] Pod my-cluster-kafka-clients-5d498854bf-96247 deleted 2020-05-24 09:00:01 [32mINFO [m [ResourceManager:227] Deleting NetworkPolicy jaeger-allow in namespace tracing-cluster-test 2020-05-24 09:00:01 [32mINFO [m [ResourceManager:359] -----METHOD RESOURCES CLEARED----- 2020-05-24 09:00:01 [32mINFO [m [ResourceManager:344] -----CLEARING CLASS RESOURCES----- 2020-05-24 09:00:01 [32mINFO [m [ResourceManager:188] Deleting Deployment strimzi-cluster-operator 2020-05-24 09:00:01 [32mINFO [m [ResourceManager:334] Waiting when all the pods are terminated for Deployment strimzi-cluster-operator 2020-05-24 09:00:01 [33mWARN [m [DeploymentUtils:190] Deployment strimzi-cluster-operator is not deleted yet! Triggering force delete by cmd client! 2020-05-24 09:00:06 [32mINFO [m [ResourceManager:202] Deleting RoleBinding strimzi-cluster-operator-topic-operator-delegation 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:202] Deleting RoleBinding strimzi-cluster-operator-entity-operator-delegation 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:195] Deleting ClusterRoleBinding strimzi-cluster-operator-kafka-broker-delegation 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:195] Deleting ClusterRoleBinding strimzi-cluster-operator 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:202] Deleting RoleBinding strimzi-cluster-operator 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:227] Deleting NetworkPolicy global-network-policy in namespace tracing-cluster-test 2020-05-24 09:00:07 [32mINFO [m [ResourceManager:349] -----CLASS RESOURCES CLEARED----- 2020-05-24 09:00:07 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/049-Crd-kafkarebalance.yaml 2020-05-24 09:00:07 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/048-Crd-kafkamirrormaker2.yaml 2020-05-24 09:00:07 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/047-Crd-kafkaconnector.yaml 2020-05-24 09:00:08 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/046-Crd-kafkabridge.yaml 2020-05-24 09:00:08 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/045-Crd-kafkamirrormaker.yaml 2020-05-24 09:00:08 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/044-Crd-kafkauser.yaml 2020-05-24 09:00:09 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/043-Crd-kafkatopic.yaml 2020-05-24 09:00:09 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/042-Crd-kafkaconnects2i.yaml 2020-05-24 09:00:10 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/041-Crd-kafkaconnect.yaml 2020-05-24 09:00:10 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/040-Crd-kafka.yaml 2020-05-24 09:00:11 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/032-ClusterRole-strimzi-topic-operator.yaml 2020-05-24 09:00:11 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/031-ClusterRole-strimzi-entity-operator.yaml 2020-05-24 09:00:12 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/030-ClusterRole-strimzi-kafka-broker.yaml 2020-05-24 09:00:12 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/021-ClusterRole-strimzi-cluster-operator-role.yaml 2020-05-24 09:00:12 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/020-ClusterRole-strimzi-cluster-operator-role.yaml 2020-05-24 09:00:13 [32mINFO [m [KubeClusterResource:175] Deleting configuration file: ../install/cluster-operator/010-ServiceAccount-strimzi-cluster-operator.yaml 2020-05-24 09:00:13 [32mINFO [m [KubeClusterResource:220] Deleting Namespace tracing-cluster-test 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:226] Using Namespace tracing-cluster-test 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:118] Changing to tracing-cluster-test namespace 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:195] Creating Namespace tracing-cluster-test 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:201] Using Namespace tracing-cluster-test 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:118] Changing to tracing-cluster-test namespace 2020-05-24 09:00:23 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/010-ServiceAccount-strimzi-cluster-operator.yaml 2020-05-24 09:00:25 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/020-ClusterRole-strimzi-cluster-operator-role.yaml 2020-05-24 09:00:25 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/021-ClusterRole-strimzi-cluster-operator-role.yaml 2020-05-24 09:00:26 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/030-ClusterRole-strimzi-kafka-broker.yaml 2020-05-24 09:00:26 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/031-ClusterRole-strimzi-entity-operator.yaml 2020-05-24 09:00:27 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/032-ClusterRole-strimzi-topic-operator.yaml 2020-05-24 09:00:27 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/040-Crd-kafka.yaml 2020-05-24 09:00:28 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/041-Crd-kafkaconnect.yaml 2020-05-24 09:00:29 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/042-Crd-kafkaconnects2i.yaml 2020-05-24 09:00:30 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/043-Crd-kafkatopic.yaml 2020-05-24 09:00:32 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/044-Crd-kafkauser.yaml 2020-05-24 09:00:32 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/045-Crd-kafkamirrormaker.yaml 2020-05-24 09:00:33 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/046-Crd-kafkabridge.yaml 2020-05-24 09:00:34 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/047-Crd-kafkaconnector.yaml 2020-05-24 09:00:35 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/048-Crd-kafkamirrormaker2.yaml 2020-05-24 09:00:35 [32mINFO [m [KubeClusterResource:97] Applying configuration file: ../install/cluster-operator/049-Crd-kafkarebalance.yaml 2020-05-24 09:00:36 [32mINFO [m [ResourceManager:105] Setting pointer to class resources 2020-05-24 09:00:36 [32mINFO [m [KubernetesResource:143] Creating RoleBinding from ../install/cluster-operator/020-RoleBinding-strimzi-cluster-operator.yaml in namespace tracing-cluster-test 2020-05-24 09:00:36 [32mINFO [m [KubernetesResource:158] Creating ClusterRoleBinding from ../install/cluster-operator/021-ClusterRoleBinding-strimzi-cluster-operator.yaml in namespace tracing-cluster-test 2020-05-24 09:00:36 [32mINFO [m [KubernetesResource:158] Creating ClusterRoleBinding from ../install/cluster-operator/030-ClusterRoleBinding-strimzi-cluster-operator-kafka-broker-delegation.yaml in namespace tracing-cluster-test 2020-05-24 09:00:36 [32mINFO [m [KubernetesResource:143] Creating RoleBinding from ../install/cluster-operator/031-RoleBinding-strimzi-cluster-operator-entity-operator-delegation.yaml in namespace tracing-cluster-test 2020-05-24 09:00:36 [32mINFO [m [KubernetesResource:143] Creating RoleBinding from ../install/cluster-operator/032-RoleBinding-strimzi-cluster-operator-topic-operator-delegation.yaml in namespace tracing-cluster-test 2020-05-24 09:01:00 [32mINFO [m [DeploymentUtils:173] Waiting for 1 Pod(s) of Deployment strimzi-cluster-operator to be ready 2020-05-24 09:01:11 [32mINFO [m [DeploymentUtils:176] Deployment strimzi-cluster-operator is ready 2020-05-24 09:01:11 [32mINFO [m [KubernetesResource:406] Network policy successfully set to: DEFAULT_TO_DENY 2020-05-24 09:01:11 [32mINFO [m [KubernetesResource:323] NetworkPolicy successfully set to: true for namespace: tracing-cluster-test 2020-05-24 09:01:11 [32mINFO [m [BaseST:760] Env recreated. 2020-05-24 09:01:11 [32mINFO [m [ResourceManager:353] -----CLEARING METHOD RESOURCES----- 2020-05-24 09:01:11 [32mINFO [m [ResourceManager:359] -----METHOD RESOURCES CLEARED----- 2020-05-24 09:01:11 [32mINFO [m [TimeMeasuringSystem:113] End time of operation TEST_EXECUTION is correctly stored 2020-05-24 09:01:11 [32mINFO [m [TestSeparator:34] io.strimzi.systemtest.tracing.TracingST.testKafkaBridgeService-FINISHED 2020-05-24 09:01:11 [32mINFO [m [TestSeparator:35] ############################################################################
Time: 05/24/2020 13:52:38, Level: ERROR, Log: java.util.concurrent.TimeoutException at java.base/java.util.concurrent.CompletableFuture.timedGet(CompletableFuture.java:1886) at java.base/java.util.concurrent.CompletableFuture.get(CompletableFuture.java:2021) at io.strimzi.systemtest.utils.HttpUtils.sendMessagesHttpRequest(HttpUtils.java:114) at io.strimzi.systemtest.tracing.TracingST.testKafkaBridgeService(TracingST.java:802) 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 org.junit.platform.commons.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:675) at org.junit.jupiter.engine.execution.MethodInvocation.proceed(MethodInvocation.java:60) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$ValidatingInvocation.proceed(InvocationInterceptorChain.java:125) at org.junit.jupiter.engine.extension.TimeoutExtension.intercept(TimeoutExtension.java:132) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestableMethod(TimeoutExtension.java:124) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestMethod(TimeoutExtension.java:74) at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall.lambda$ofVoidMethod$0(ExecutableInvoker.java:115) at org.junit.jupiter.engine.execution.ExecutableInvoker.lambda$invoke$0(ExecutableInvoker.java:105) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$InterceptedInvocation.proceed(InvocationInterceptorChain.java:104) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.proceed(InvocationInterceptorChain.java:62) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.chainAndInvoke(InvocationInterceptorChain.java:43) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.invoke(InvocationInterceptorChain.java:35) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:104) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:98) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$6(TestMethodTestDescriptor.java:202) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:198) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:135) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:69) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:135) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125) at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122) at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80) at java.base/java.util.ArrayList.forEach(ArrayList.java:1540) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125) at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122) at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80) at java.base/java.util.ArrayList.forEach(ArrayList.java:1540) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125) at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122) at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.submit(SameThreadHierarchicalTestExecutorService.java:32) at org.junit.platform.engine.support.hierarchical.HierarchicalTestExecutor.execute(HierarchicalTestExecutor.java:57) at org.junit.platform.engine.support.hierarchical.HierarchicalTestEngine.execute(HierarchicalTestEngine.java:51) at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:229) at org.junit.platform.launcher.core.DefaultLauncher.lambda$execute$6(DefaultLauncher.java:197) at org.junit.platform.launcher.core.DefaultLauncher.withInterceptedStreams(DefaultLauncher.java:211) at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:191) at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:128) at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invokeAllTests(JUnitPlatformProvider.java:142) at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invoke(JUnitPlatformProvider.java:117) at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)