-
Bug
-
Resolution: Done
-
Major
-
1.5.0.GA
-
None
Back link to Report Portal:
Test execution log:
Time: 04/19/2020 07:07:01, Level: INFO, Log: 2020-04-18 23:52:21 [32mINFO [m [TimeMeasuringSystem:94] Start time of operation TEST_EXECUTION is correctly stored 2020-04-18 23:52:21 [32mINFO [m [TestSeparator:27] ############################################################################ 2020-04-18 23:52:21 [32mINFO [m [TestSeparator:28] io.strimzi.systemtest.ConnectST.testKafkaConnectWithFileSinkPlugin-STARTED 2020-04-18 23:52:21 [32mINFO [m [ResourceManager:89] Setting pointer to method resources 2020-04-18 23:52:21 [32mINFO [m [ResourceManager:106] Scheduled deletion of Kafka my-cluster in namespace connect-cluster-test 2020-04-18 23:52:21 [32mINFO [m [KafkaResource:213] Waiting for Kafka my-cluster in namespace connect-cluster-test 2020-04-18 23:52:21 [32mINFO [m [KafkaResource:215] Waiting for Zookeeper pods 2020-04-18 23:53:05 [32mINFO [m [KafkaResource:217] Zookeeper pods are ready 2020-04-18 23:53:05 [32mINFO [m [KafkaResource:219] Waiting for Kafka pods 2020-04-18 23:53:43 [32mINFO [m [KafkaResource:221] Kafka pods are ready 2020-04-18 23:53:43 [32mINFO [m [KafkaResource:225] Waiting for Entity Operator pods 2020-04-18 23:54:08 [32mINFO [m [KafkaResource:227] Entity Operator pods are ready 2020-04-18 23:54:08 [32mINFO [m [KubernetesResource:342] Apply NetworkPolicy access to my-cluster-connect from my-cluster-kafka-clients 2020-04-18 23:54:08 [32mINFO [m [ResourceManager:106] Scheduled deletion of NetworkPolicy my-cluster-allow in namespace connect-cluster-test 2020-04-18 23:54:08 [32mINFO [m [KubernetesResource:379] Network policy for my-cluster-kafka-clients successfully applied 2020-04-18 23:54:08 [32mINFO [m [ResourceManager:106] Scheduled deletion of KafkaConnect my-cluster in namespace connect-cluster-test 2020-04-18 23:54:08 [32mINFO [m [KafkaConnectResource:130] Waiting for Kafka Connect my-cluster 2020-04-18 23:55:32 [32mINFO [m [KafkaConnectResource:132] Kafka Connect my-cluster is ready 2020-04-18 23:55:32 [32mINFO [m [KafkaTopicResource:66] Created KafkaTopic connect-topic-example 2020-04-18 23:55:32 [32mINFO [m [ResourceManager:106] Scheduled deletion of KafkaTopic connect-topic-example in namespace connect-cluster-test 2020-04-18 23:55:32 [32mINFO [m [KafkaTopicResource:83] Waiting for Kafka Topic connect-topic-example 2020-04-18 23:55:32 [32mINFO [m [KafkaTopicUtils:46] Waiting for Kafka topic creation connect-topic-example 2020-04-18 23:55:33 [32mINFO [m [KafkaTopicResource:85] Kafka Topic connect-topic-example is ready 2020-04-18 23:55:33 [32mINFO [m [KafkaConnectUtils:39] Waiting until kafka connect service is present 2020-04-18 23:55:34 [32mINFO [m [KafkaConnectUtils:42] Kafka connect service is present 2020-04-18 23:55:35 [32mINFO [m [InternalKafkaClient:79] Starting verifiableClient plain producer with following configuration VerifiableClient{allowedArguments=[TOPIC, BROKER_LIST, MAX_MESSAGES, THROUGHPUT, ACKS, PRODUCER_CONFIG, MESSAGE_CREATE_TIME, VALUE_PREFIX, REPEATING_KEYS, USER], lock=java.lang.Object@78b8f818, messages=[], arguments=[--topic, connect-topic-example, --broker-list, my-cluster-kafka-bootstrap:9092, --max-messages, 100], executable='/opt/kafka/producer.sh', executor=null, clientType=CLI_KAFKA_VERIFIABLE_PRODUCER, podName='my-cluster-connect-6cd677c8d-xlmdh', podNamespace='connect-cluster-test', bootstrapServer='my-cluster-kafka-bootstrap:9092', topicName='connect-topic-example', maxMessages=100, kafkaUsername='null', consumerGroupName='null', consumerInstanceId='null', clientArgumentMap=io.strimzi.systemtest.kafkaclients.internalClients.ClientArgumentMap@1e9d721} 2020-04-18 23:55:35 [32mINFO [m [InternalKafkaClient:80] Sending 100 messages to my-cluster-kafka-bootstrap:9092#connect-topic-example 2020-04-18 23:55:35 [32mINFO [m [InternalKafkaClient:83] Sending 100 messages to my-cluster-connect-6cd677c8d-xlmdh 2020-04-18 23:55:36 [32mINFO [m [VerifiableClient:203] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER Return code - 1 2020-04-18 23:55:36 [32mINFO [m [VerifiableClient:205] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stdout : 2020-04-18 23:55:36 [1;31mERROR[m [VerifiableClient:209] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stderr : time="2020-04-18T23:55:35Z" level=error msg="exec failed: container_linux.go:346: starting container process caused \"exec: \\\"/opt/kafka/producer.sh\\\": stat /opt/kafka/producer.sh: no such file or directory\"" exec failed: container_linux.go:346: starting container process caused "exec: \"/opt/kafka/producer.sh\": stat /opt/kafka/producer.sh: no such file or directory" command terminated with exit code 1 2020-04-18 23:56:06 [32mINFO [m [InternalKafkaClient:83] Sending 100 messages to my-cluster-connect-6cd677c8d-xlmdh 2020-04-18 23:56:06 [32mINFO [m [VerifiableClient:203] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER Return code - 1 2020-04-18 23:56:06 [32mINFO [m [VerifiableClient:205] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stdout : 2020-04-18 23:56:06 [1;31mERROR[m [VerifiableClient:209] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stderr : time="2020-04-18T23:56:05Z" level=error msg="exec failed: container_linux.go:346: starting container process caused \"exec: \\\"/opt/kafka/producer.sh\\\": stat /opt/kafka/producer.sh: no such file or directory\"" exec failed: container_linux.go:346: starting container process caused "exec: \"/opt/kafka/producer.sh\": stat /opt/kafka/producer.sh: no such file or directory" command terminated with exit code 1 2020-04-18 23:56:36 [32mINFO [m [InternalKafkaClient:83] Sending 100 messages to my-cluster-connect-6cd677c8d-xlmdh 2020-04-18 23:56:36 [32mINFO [m [VerifiableClient:203] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER Return code - 1 2020-04-18 23:56:36 [32mINFO [m [VerifiableClient:205] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stdout : 2020-04-18 23:56:36 [1;31mERROR[m [VerifiableClient:209] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stderr : time="2020-04-18T23:56:35Z" level=error msg="exec failed: container_linux.go:346: starting container process caused \"exec: \\\"/opt/kafka/producer.sh\\\": stat /opt/kafka/producer.sh: no such file or directory\"" exec failed: container_linux.go:346: starting container process caused "exec: \"/opt/kafka/producer.sh\": stat /opt/kafka/producer.sh: no such file or directory" command terminated with exit code 1 2020-04-18 23:57:06 [32mINFO [m [InternalKafkaClient:83] Sending 100 messages to my-cluster-connect-6cd677c8d-xlmdh 2020-04-18 23:57:07 [32mINFO [m [VerifiableClient:203] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER Return code - 1 2020-04-18 23:57:07 [32mINFO [m [VerifiableClient:205] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stdout : 2020-04-18 23:57:07 [1;31mERROR[m [VerifiableClient:209] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stderr : time="2020-04-18T23:57:06Z" level=error msg="exec failed: container_linux.go:346: starting container process caused \"exec: \\\"/opt/kafka/producer.sh\\\": stat /opt/kafka/producer.sh: no such file or directory\"" exec failed: container_linux.go:346: starting container process caused "exec: \"/opt/kafka/producer.sh\": stat /opt/kafka/producer.sh: no such file or directory" command terminated with exit code 1 2020-04-18 23:57:35 [32mINFO [m [InternalKafkaClient:83] Sending 100 messages to my-cluster-connect-6cd677c8d-xlmdh 2020-04-18 23:57:36 [32mINFO [m [VerifiableClient:203] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER Return code - 1 2020-04-18 23:57:36 [32mINFO [m [VerifiableClient:205] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stdout : 2020-04-18 23:57:36 [1;31mERROR[m [VerifiableClient:209] VerifiableClient CLI_KAFKA_VERIFIABLE_PRODUCER stderr : time="2020-04-18T23:57:34Z" level=error msg="exec failed: container_linux.go:346: starting container process caused \"exec: \\\"/opt/kafka/producer.sh\\\": stat /opt/kafka/producer.sh: no such file or directory\"" exec failed: container_linux.go:346: starting container process caused "exec: \"/opt/kafka/producer.sh\": stat /opt/kafka/producer.sh: no such file or directory" command terminated with exit code 1 2020-04-18 23:57:36 [32mINFO [m [LogCollector:64] Collecting events in namespace connect-cluster-test 2020-04-18 23:57:36 [32mINFO [m [LogCollector:71] Collecting configmaps in namespace connect-cluster-test 2020-04-18 23:57:36 [32mINFO [m [LogCollector:44] Collecting logs for pods in namespace connect-cluster-test 2020-04-18 23:57:40 [32mINFO [m [LogCollector:78] Collecting Deployments in namespaces connect-cluster-test 2020-04-18 23:57:41 [32mINFO [m [LogCollector:83] Collecting StatefulSets in namespaces connect-cluster-test 2020-04-18 23:57:41 [32mINFO [m [LogCollector:88] Collecting ReplicaSet in namespaces connect-cluster-test 2020-04-18 23:57:41 [32mINFO [m [LogCollector:93] Collecting CR in namespaces connect-cluster-test 2020-04-18 23:57:42 [32mINFO [m [Exec:166] Command: [oc, get, strimzi, -o, yaml, -n, connect-cluster-test] 2020-04-18 23:57:42 [32mINFO [m [Exec:167] Return code: 0 2020-04-18 23:57:42 [32mINFO [m [Exec:168] stdout: apiVersion: v1 items: - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaConnect metadata: creationTimestamp: "2020-04-18T23:54:07Z" generation: 1 labels: type: kafka-connect name: my-cluster namespace: connect-cluster-test resourceVersion: "442424" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkaconnects/my-cluster uid: a98415a9-de5e-488c-95f3-02895424a053 spec: bootstrapServers: my-cluster-kafka-bootstrap:9093 config: config.storage.topic: my-cluster-connect-config group.id: my-cluster-connect key.converter: org.apache.kafka.connect.storage.StringConverter key.converter.schemas.enable: false offset.storage.topic: my-cluster-connect-offsets status.storage.topic: my-cluster-connect-status value.converter: org.apache.kafka.connect.storage.StringConverter value.converter.schemas.enable: false logging: loggers: connect.root.logger.level: DEBUG type: inline replicas: 1 tls: trustedCertificates: - certificate: ca.crt secretName: my-cluster-cluster-ca-cert version: 2.4.0 status: conditions: - lastTransitionTime: "2020-04-18T23:55:20.423Z" status: "True" type: Ready observedGeneration: 1 url: http://my-cluster-connect-api.connect-cluster-test.svc:8083 - apiVersion: kafka.strimzi.io/v1beta1 kind: Kafka metadata: creationTimestamp: "2020-04-18T23:52:20Z" generation: 1 name: my-cluster namespace: connect-cluster-test resourceVersion: "442162" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkas/my-cluster uid: 36f82ae5-1036-4af2-a3ad-1b52f58ee6ed 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.4" offsets.topic.replication.factor: 3 transaction.state.log.min.isr: 2 transaction.state.log.replication.factor: 3 listeners: plain: {} tls: {} logging: loggers: kafka.root.logger.level: DEBUG type: inline replicas: 3 storage: type: ephemeral version: 2.4.0 zookeeper: logging: loggers: zookeeper.root.logger: DEBUG type: inline replicas: 3 storage: type: ephemeral status: conditions: - lastTransitionTime: 2020-04-18T23:54:06+0000 status: "True" type: Ready listeners: - addresses: - host: my-cluster-kafka-bootstrap.connect-cluster-test.svc port: 9092 type: plain - addresses: - host: my-cluster-kafka-bootstrap.connect-cluster-test.svc port: 9093 certificates: - | -----BEGIN CERTIFICATE----- MIIDLTCCAhWgAwIBAgIJAKLiPNi8xy1TMA0GCSqGSIb3DQEBCwUAMC0xEzARBgNV BAoMCmlvLnN0cmltemkxFjAUBgNVBAMMDWNsdXN0ZXItY2EgdjAwHhcNMjAwNDE4 MjM1MjIwWhcNMjEwNDE4MjM1MjIwWjAtMRMwEQYDVQQKDAppby5zdHJpbXppMRYw FAYDVQQDDA1jbHVzdGVyLWNhIHYwMIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIB CgKCAQEA3pwp0b8Y4PmJdIEKxTMBp73iVi2C1h3egWjjIHddyhovhonH/OlAd75i +7VuKwVlaWyKyUK6BusAfoR6NQTnULhzbnR7395VdutrvTZMLF5AH5F+buNKVgOa 20mdOE3++MPt2JEZ9nA3zyUZ4qtb4MvOzrOeLhn9izpVNd8Dnt254t5GjYxXuQmL QujsHNme8tLacXlAIAIbWqpBeMLpLEl27/OoG3xGghD6oOJrdQZocnR3ak2AtPAL vIY01HnrqqRqX1i3g/M/ulJK7UsQoCDksYNn1pvR8f3ue1ZRY+SSicsS5IGEaR1x TWGjOOjcFCTTEZxwDUUeIc8nRbbKmQIDAQABo1AwTjAdBgNVHQ4EFgQUks8wf4iG 044QBQpjX0G+m5elcYowHwYDVR0jBBgwFoAUks8wf4iG044QBQpjX0G+m5elcYow DAYDVR0TBAUwAwEB/zANBgkqhkiG9w0BAQsFAAOCAQEAt3MASsL2HQtZaNlyoBKC DtFD/GNxH7dbGyJjpSp/gsDVdIh86iSMnNCZ/P5KOGdLvm4pax2lMZcx96WutoeT vBpzCjZ747z4FvJbIZLzMQd8XDYfWZ/xP59WvFL22BWewJAjDLe31rg3oTEAYbGf BGYIYnd8a1SijnhnO/S1F7xqLqC4UDqpbIodiAHhEutwEK4NNFjeFL4Shnn/JikY 2JN4W96ctefO4WnCKDlJwH+idX/4FjNzrZo+iMmS9oLD7e7/vVBx3s/Xuko0740f kJUJHIn38JnISgoXRpe7sRjHwZWGFZxNIt2+QppC1mS+hesir/IsdoOS/HzGYjxg wg== -----END CERTIFICATE----- type: tls observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-04-18T23:55:31Z" generation: 2 labels: strimzi.io/cluster: my-cluster name: connect-topic-example namespace: connect-cluster-test resourceVersion: "442469" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkatopics/connect-topic-example uid: b36a42e6-aeca-4822-a1a0-cacbce199b4d spec: config: message.format.version: 2.4-IV1 min.insync.replicas: "1" retention.ms: "7200000" segment.bytes: "1073741824" partitions: 1 replicas: 1 topicName: connect-topic-example status: conditions: - lastTransitionTime: "2020-04-18T23:55:32.331Z" status: "True" type: Ready observedGeneration: 2 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-04-18T23:50:03Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: consumer-offsets---84e7a678d08f4bd226872e5cdd4eb527fadc1c6a namespace: connect-cluster-test resourceVersion: "442514" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkatopics/consumer-offsets---84e7a678d08f4bd226872e5cdd4eb527fadc1c6a uid: 8b872938-ef8d-447d-b497-9ce803c4863c spec: config: cleanup.policy: compact compression.type: producer message.format.version: 2.4-IV1 segment.bytes: "104857600" partitions: 50 replicas: 3 topicName: __consumer_offsets status: conditions: - lastTransitionTime: "2020-04-18T23:55:45.418Z" status: "True" type: Ready observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-04-18T23:49:07Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: my-cluster-connect-config namespace: connect-cluster-test resourceVersion: "440901" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkatopics/my-cluster-connect-config uid: 97f66e6d-bc5d-4590-9df4-41287d6b7bf7 spec: config: cleanup.policy: compact message.format.version: 2.4-IV1 partitions: 1 replicas: 3 topicName: my-cluster-connect-config status: conditions: - lastTransitionTime: "2020-04-18T23:49:07.977Z" status: "True" type: Ready observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-04-18T23:49:05Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: my-cluster-connect-offsets namespace: connect-cluster-test resourceVersion: "440896" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkatopics/my-cluster-connect-offsets uid: 309ebb80-5d50-4b44-98a7-e0535398c6e8 spec: config: cleanup.policy: compact message.format.version: 2.4-IV1 partitions: 25 replicas: 3 topicName: my-cluster-connect-offsets status: conditions: - lastTransitionTime: "2020-04-18T23:49:05.669Z" status: "True" type: Ready observedGeneration: 1 - apiVersion: kafka.strimzi.io/v1beta1 kind: KafkaTopic metadata: creationTimestamp: "2020-04-18T23:49:05Z" generation: 1 labels: strimzi.io/cluster: my-cluster name: my-cluster-connect-status namespace: connect-cluster-test resourceVersion: "440895" selfLink: /apis/kafka.strimzi.io/v1beta1/namespaces/connect-cluster-test/kafkatopics/my-cluster-connect-status uid: bf68a04c-bdbf-400f-9542-b50044b31c5b spec: config: cleanup.policy: compact message.format.version: 2.4-IV1 partitions: 5 replicas: 3 topicName: my-cluster-connect-status status: conditions: - lastTransitionTime: "2020-04-18T23:49:05.509Z" status: "True" type: Ready observedGeneration: 1 kind: List metadata: resourceVersion: "" selfLink: "" 2020-04-18 23:57:42 [32mINFO [m [Exec:169] stderr: 2020-04-18 23:57:42 [32mINFO [m [BaseST:666] Search in strimzi-cluster-operator log for errors in last 0 seconds 2020-04-18 23:57:42 [32mINFO [m [BaseST:750] Test execution contains exception, going to recreate test environment 2020-04-18 23:57:42 [32mINFO [m [ResourceManager:341] Going to clear all method resources 2020-04-18 23:57:42 [32mINFO [m [ResourceManager:216] Deleting KafkaTopic connect-topic-example in namespace connect-cluster-test 2020-04-18 23:57:42 [32mINFO [m [ResourceManager:122] Deleting KafkaConnect my-cluster in namespace connect-cluster-test 2020-04-18 23:57:42 [32mINFO [m [ResourceManager:265] Waiting when all the pods are terminated for Kafka Connect my-cluster 2020-04-18 23:57:42 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-connect-6cd677c8d-xlmdh will be deleted 2020-04-18 23:58:01 [32mINFO [m [PodUtils:153] Pod my-cluster-connect-6cd677c8d-xlmdh deleted 2020-04-18 23:58:01 [32mINFO [m [ResourceManager:216] Deleting NetworkPolicy my-cluster-allow in namespace connect-cluster-test 2020-04-18 23:58:01 [32mINFO [m [ResourceManager:111] Deleting Kafka my-cluster in namespace connect-cluster-test 2020-04-18 23:58:01 [32mINFO [m [ResourceManager:229] Waiting when all the pods are terminated for Kafka my-cluster 2020-04-18 23:58:01 [33mWARN [m [StatefulSetUtils:159] StatefulSet my-cluster-zookeeper is not deleted yet! Triggering force delete by cmd client! 2020-04-18 23:58:06 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-zookeeper-0 will be deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:153] Pod my-cluster-zookeeper-0 deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-zookeeper-1 will be deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:153] Pod my-cluster-zookeeper-1 deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-zookeeper-2 will be deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:153] Pod my-cluster-zookeeper-2 deleted 2020-04-18 23:58:21 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-kafka-0 will be deleted 2020-04-18 23:58:39 [32mINFO [m [PodUtils:153] Pod my-cluster-kafka-0 deleted 2020-04-18 23:58:39 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-kafka-1 will be deleted 2020-04-18 23:58:39 [32mINFO [m [PodUtils:153] Pod my-cluster-kafka-1 deleted 2020-04-18 23:58:39 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-kafka-2 will be deleted 2020-04-18 23:58:51 [32mINFO [m [PodUtils:153] Pod my-cluster-kafka-2 deleted 2020-04-18 23:58:51 [32mINFO [m [SecretUtils:95] Waiting for Kafka cluster my-cluster secrets deletion 2020-04-18 23:58:51 [32mINFO [m [SecretUtils:109] Kafka cluster my-cluster secrets deleted 2020-04-18 23:58:51 [32mINFO [m [PersistentVolumeClaimUtils:58] Waiting till PVC deletion for cluster my-cluster 2020-04-18 23:58:51 [32mINFO [m [PersistentVolumeClaimUtils:72] PVC for cluster my-cluster was deleted 2020-04-18 23:58:51 [32mINFO [m [ConfigMapUtils:66] Waiting till ConfigMaps deletion for cluster my-cluster-kafka-config 2020-04-18 23:58:51 [32mINFO [m [ConfigMapUtils:80] ConfigMaps for cluster my-cluster-kafka-config were deleted 2020-04-18 23:58:51 [32mINFO [m [ConfigMapUtils:66] Waiting till ConfigMaps deletion for cluster my-cluster-zookeeper-config 2020-04-18 23:58:51 [32mINFO [m [ConfigMapUtils:80] ConfigMaps for cluster my-cluster-zookeeper-config were deleted 2020-04-18 23:58:51 [32mINFO [m [ResourceManager:333] Going to clear all class resources 2020-04-18 23:58:51 [32mINFO [m [ResourceManager:216] Deleting NetworkPolicy global-network-policy in namespace connect-cluster-test 2020-04-18 23:58:51 [32mINFO [m [ResourceManager:177] Deleting Deployment my-cluster-kafka-clients 2020-04-18 23:58:51 [32mINFO [m [ResourceManager:323] Waiting when all the pods are terminated for Deployment my-cluster-kafka-clients 2020-04-18 23:58:51 [33mWARN [m [DeploymentUtils:187] Deployment my-cluster-kafka-clients is not deleted yet! Triggering force delete by cmd client! 2020-04-18 23:58:56 [32mINFO [m [PodUtils:138] Waiting when Pod my-cluster-kafka-clients-5f986b8764-f2srk will be deleted 2020-04-18 23:59:29 [32mINFO [m [PodUtils:153] Pod my-cluster-kafka-clients-5f986b8764-f2srk deleted 2020-04-18 23:59:29 [32mINFO [m [ResourceManager:177] Deleting Deployment strimzi-cluster-operator 2020-04-18 23:59:29 [32mINFO [m [ResourceManager:323] Waiting when all the pods are terminated for Deployment strimzi-cluster-operator 2020-04-18 23:59:29 [33mWARN [m [DeploymentUtils:187] Deployment strimzi-cluster-operator is not deleted yet! Triggering force delete by cmd client! 2020-04-18 23:59:34 [32mINFO [m [ResourceManager:191] Deleting RoleBinding strimzi-cluster-operator-topic-operator-delegation 2020-04-18 23:59:34 [32mINFO [m [ResourceManager:191] Deleting RoleBinding strimzi-cluster-operator-entity-operator-delegation 2020-04-18 23:59:34 [32mINFO [m [ResourceManager:184] Deleting ClusterRoleBinding strimzi-cluster-operator-kafka-broker-delegation 2020-04-18 23:59:34 [32mINFO [m [ResourceManager:184] Deleting ClusterRoleBinding strimzi-cluster-operator 2020-04-18 23:59:34 [32mINFO [m [ResourceManager:191] Deleting RoleBinding strimzi-cluster-operator 2020-04-18 23:59:34 [32mINFO [m [TimeMeasuringSystem:94] Start time of operation CO_DELETION is correctly stored 2020-04-18 23:59:34 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/048-Crd-kafkamirrormaker2.yaml 2020-04-18 23:59:34 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/047-Crd-kafkaconnector.yaml 2020-04-18 23:59:35 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/046-Crd-kafkabridge.yaml 2020-04-18 23:59:35 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/045-Crd-kafkamirrormaker.yaml 2020-04-18 23:59:35 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/044-Crd-kafkauser.yaml 2020-04-18 23:59:35 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/043-Crd-kafkatopic.yaml 2020-04-18 23:59:35 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/042-Crd-kafkaconnects2i.yaml 2020-04-18 23:59:36 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/041-Crd-kafkaconnect.yaml 2020-04-18 23:59:36 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/040-Crd-kafka.yaml 2020-04-18 23:59:37 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/032-ClusterRole-strimzi-topic-operator.yaml 2020-04-18 23:59:37 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/031-ClusterRole-strimzi-entity-operator.yaml 2020-04-18 23:59:37 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/030-ClusterRole-strimzi-kafka-broker.yaml 2020-04-18 23:59:38 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/021-ClusterRole-strimzi-cluster-operator-role.yaml 2020-04-18 23:59:38 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/020-ClusterRole-strimzi-cluster-operator-role.yaml 2020-04-18 23:59:38 [32mINFO [m [KubeClusterResource:185] Deleting configuration file: ../install/cluster-operator/010-ServiceAccount-strimzi-cluster-operator.yaml 2020-04-18 23:59:38 [32mINFO [m [TimeMeasuringSystem:113] End time of operation CO_DELETION is correctly stored 2020-04-18 23:59:38 [32mINFO [m [KubeClusterResource:231] Deleting namespace: connect-cluster-test 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:237] Using namespace connect-cluster-test 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:125] Changing to connect-cluster-test namespace 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:206] Creating namespace: connect-cluster-test 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:212] Using namespace connect-cluster-test 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:125] Changing to connect-cluster-test namespace 2020-04-18 23:59:46 [32mINFO [m [TimeMeasuringSystem:94] Start time of operation CO_CREATION is correctly stored 2020-04-18 23:59:46 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/010-ServiceAccount-strimzi-cluster-operator.yaml 2020-04-18 23:59:47 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/020-ClusterRole-strimzi-cluster-operator-role.yaml 2020-04-18 23:59:48 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/021-ClusterRole-strimzi-cluster-operator-role.yaml 2020-04-18 23:59:48 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/030-ClusterRole-strimzi-kafka-broker.yaml 2020-04-18 23:59:48 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/031-ClusterRole-strimzi-entity-operator.yaml 2020-04-18 23:59:49 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/032-ClusterRole-strimzi-topic-operator.yaml 2020-04-18 23:59:49 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/040-Crd-kafka.yaml 2020-04-18 23:59:50 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/041-Crd-kafkaconnect.yaml 2020-04-18 23:59:50 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/042-Crd-kafkaconnects2i.yaml 2020-04-18 23:59:51 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/043-Crd-kafkatopic.yaml 2020-04-18 23:59:51 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/044-Crd-kafkauser.yaml 2020-04-18 23:59:52 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/045-Crd-kafkamirrormaker.yaml 2020-04-18 23:59:52 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/046-Crd-kafkabridge.yaml 2020-04-18 23:59:53 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/047-Crd-kafkaconnector.yaml 2020-04-18 23:59:53 [32mINFO [m [KubeClusterResource:103] Applying configuration file: ../install/cluster-operator/048-Crd-kafkamirrormaker2.yaml 2020-04-18 23:59:54 [32mINFO [m [TimeMeasuringSystem:113] End time of operation CO_CREATION is correctly stored 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:94] Setting pointer to class resources 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:142] Creating RoleBinding from ../install/cluster-operator/020-RoleBinding-strimzi-cluster-operator.yaml in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:151] Apply RoleBinding in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of RoleBinding strimzi-cluster-operator in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:158] Creating ClusterRoleBinding from ../install/cluster-operator/021-ClusterRoleBinding-strimzi-cluster-operator.yaml in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:167] Apply ClusterRoleBinding in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of ClusterRoleBinding strimzi-cluster-operator in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:158] Creating ClusterRoleBinding from ../install/cluster-operator/030-ClusterRoleBinding-strimzi-cluster-operator-kafka-broker-delegation.yaml in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:167] Apply ClusterRoleBinding in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of ClusterRoleBinding strimzi-cluster-operator-kafka-broker-delegation in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:142] Creating RoleBinding from ../install/cluster-operator/031-RoleBinding-strimzi-cluster-operator-entity-operator-delegation.yaml in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:151] Apply RoleBinding in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of RoleBinding strimzi-cluster-operator-entity-operator-delegation in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:142] Creating RoleBinding from ../install/cluster-operator/032-RoleBinding-strimzi-cluster-operator-topic-operator-delegation.yaml in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:151] Apply RoleBinding in namespace connect-cluster-test 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of RoleBinding strimzi-cluster-operator-topic-operator-delegation in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [ResourceManager:106] Scheduled deletion of Deployment strimzi-cluster-operator in namespace (not set) 2020-04-18 23:59:54 [32mINFO [m [KubernetesResource:427] Waiting for deployment strimzi-cluster-operator 2020-04-19 00:00:38 [32mINFO [m [KubernetesResource:429] Deployment strimzi-cluster-operator is ready 2020-04-19 00:00:38 [32mINFO [m [ResourceManager:106] Scheduled deletion of Deployment my-cluster-kafka-clients in namespace (not set) 2020-04-19 00:00:38 [32mINFO [m [KubernetesResource:427] Waiting for deployment my-cluster-kafka-clients 2020-04-19 00:01:00 [32mINFO [m [KubernetesResource:429] Deployment my-cluster-kafka-clients is ready 2020-04-19 00:01:00 [32mINFO [m [ResourceManager:106] Scheduled deletion of NetworkPolicy global-network-policy in namespace connect-cluster-test 2020-04-19 00:01:00 [32mINFO [m [KubernetesResource:407] Network policy successfully set to: DEFAULT_TO_DENY 2020-04-19 00:01:00 [32mINFO [m [KubernetesResource:324] NetworkPolicy successfully set to: true for namespace: connect-cluster-test 2020-04-19 00:01:00 [32mINFO [m [BaseST:753] Env recreated. 2020-04-19 00:01:00 [32mINFO [m [ResourceManager:341] Going to clear all method resources 2020-04-19 00:01:00 [33mWARN [m [TimeMeasuringSystem:115] End time of operation TEST_EXECUTION is not set due to exception: java.lang.NullPointerException 2020-04-19 00:01:00 [32mINFO [m [TestSeparator:34] io.strimzi.systemtest.ConnectST.testKafkaConnectWithFileSinkPlugin-FINISHED 2020-04-19 00:01:00 [32mINFO [m [TestSeparator:35] ############################################################################
Time: 04/19/2020 07:07:01, Level: ERROR, Log: Timeout after 120000 ms waiting for Sending messages
Time: 04/19/2020 07:07:01, Level: INFO, Log: logs-pod-my-cluster-connect-6cd677c8d-xlmdh-container-my-cluster-connect.log
[^5e9bf8982e738600015a470d.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-connect-6cd677c8d-xlmdh-container-my-cluster-connect.log
[^5e9bf8982e738600015a4714.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-tls-sidecar.log
[^5e9bf8982e738600015a471a.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-tls-sidecar.log
[^5e9bf8982e738600015a4724.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-topic-operator.log
[^5e9bf8982e738600015a4729.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-topic-operator.log
[^5e9bf8982e738600015a4731.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-user-operator.log
[^5e9bf8992e738600015a4739.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-entity-operator-76d54fc76d-6xhmp-container-user-operator.log
[^5e9bf8992e738600015a4740.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-kafka-0-container-kafka.log
[^5e9bf8992e738600015a4747.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-kafka-0-container-kafka.log
[^5e9bf8992e738600015a4750.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-kafka-0-container-tls-sidecar.log
[^5e9bf8992e738600015a4759.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-kafka-0-container-tls-sidecar.log
[^5e9bf8992e738600015a475d.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-kafka-1-container-kafka.log
[^5e9bf8992e738600015a4760.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: describe-pod-my-cluster-kafka-1-container-kafka.log
[^5e9bf8992e738600015a4765.txt]
Time: 04/19/2020 07:07:02, Level: INFO, Log: logs-pod-my-cluster-kafka-1-container-tls-sidecar.log
[^5e9bf8992e738600015a4768.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-kafka-1-container-tls-sidecar.log
[^5e9bf8992e738600015a476b.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-kafka-2-container-kafka.log
[^5e9bf8992e738600015a476e.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-kafka-2-container-kafka.log
[^5e9bf8992e738600015a4772.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-kafka-2-container-tls-sidecar.log
[^5e9bf8992e738600015a4775.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-kafka-2-container-tls-sidecar.log
[^5e9bf89a2e738600015a4778.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-kafka-clients-5f986b8764-f2srk-container-my-cluster-kafka-clients.log
[^5e9bf89a2e738600015a477b.bin]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-kafka-clients-5f986b8764-f2srk-container-my-cluster-kafka-clients.log
[^5e9bf89a2e738600015a477d.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-zookeeper-0-container-tls-sidecar.log
[^5e9bf89a2e738600015a4780.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-zookeeper-0-container-tls-sidecar.log
[^5e9bf89a2e738600015a4783.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-zookeeper-0-container-zookeeper.log
[^5e9bf89a2e738600015a4786.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-zookeeper-0-container-zookeeper.log
[^5e9bf89a2e738600015a4789.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-zookeeper-1-container-tls-sidecar.log
[^5e9bf89a2e738600015a478c.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: describe-pod-my-cluster-zookeeper-1-container-tls-sidecar.log
[^5e9bf89a2e738600015a478f.txt]
Time: 04/19/2020 07:07:03, Level: INFO, Log: logs-pod-my-cluster-zookeeper-1-container-zookeeper.log
[^5e9bf89a2e738600015a4792.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: describe-pod-my-cluster-zookeeper-1-container-zookeeper.log
[^5e9bf89a2e738600015a4796.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: logs-pod-my-cluster-zookeeper-2-container-tls-sidecar.log
[^5e9bf89b2e738600015a4799.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: describe-pod-my-cluster-zookeeper-2-container-tls-sidecar.log
[^5e9bf89b2e738600015a479c.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: logs-pod-my-cluster-zookeeper-2-container-zookeeper.log
[^5e9bf89b2e738600015a479f.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: describe-pod-my-cluster-zookeeper-2-container-zookeeper.log
[^5e9bf89b2e738600015a47a4.txt]
Time: 04/19/2020 07:07:04, Level: INFO, Log: logs-pod-strimzi-cluster-operator-c84994bb7-grt7x-container-strimzi-cluster-operator.log
[^5e9bf89b2e738600015a47a9.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: describe-pod-strimzi-cluster-operator-c84994bb7-grt7x-container-strimzi-cluster-operator.log
[^5e9bf89b2e738600015a47af.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: deployments.log
[^5e9bf89b2e738600015a47b2.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: statefulsets.log
[^5e9bf89c2e738600015a47b5.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: replicasets.log
[^5e9bf89c2e738600015a47b8.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: strimzi-custom-resources.log
[^5e9bf89c2e738600015a47bb.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: events-in-namespaceconnect-cluster-test.log
[^5e9bf89c2e738600015a47be.txt]
Time: 04/19/2020 07:07:05, Level: INFO, Log: my-cluster-connect-config-connect-cluster-test.log
[^5e9bf89c2e738600015a47c1.txt]
Time: 04/19/2020 07:07:06, Level: INFO, Log: my-cluster-entity-topic-operator-config-connect-cluster-test.log
[^5e9bf89c2e738600015a47c4.txt]
Time: 04/19/2020 07:07:06, Level: INFO, Log: my-cluster-entity-user-operator-config-connect-cluster-test.log
[^5e9bf89c2e738600015a47c7.txt]
Time: 04/19/2020 07:07:06, Level: INFO, Log: my-cluster-kafka-config-connect-cluster-test.log
[^5e9bf89c2e738600015a47ca.txt]
Time: 04/19/2020 07:07:06, Level: INFO, Log: my-cluster-zookeeper-config-connect-cluster-test.log
[^5e9bf89d2e738600015a47cd.txt]