Keycloak realm is not created withing timeout after re-create address space with a same name

Describe the bug
[brokered, standard] Marathon test suite failed on test testCreateDeleteAddressSpaceLong, first run test passed and delete address space, then in second run test tries to create address space with the same name and address space is not created within timeout, then in third run address space is created but keycloak realm is not created.
To Reproduce
Steps to reproduce the behavior:

Create address space "address-space-name"
create addresses and do messaging (send/receive messages)
delete address space
create address space "address-space-name" again

Expected behavior
Address space should be correctly created and it should work
Logs
docker-logs.zip
Additional context
11:50:47,007 [main] INFO io.enmasse.systemtest.ability.ITestSeparator - ####################################################################################################
11:50:47,007 [main] INFO io.enmasse.systemtest.ability.ITestSeparator - io.enmasse.systemtest.marathon.BrokeredMarathonTest.testCreateDeleteAddressSpaceLong-STARTED
11:50:47,007 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - Starting test running for 30 minutes at Mon Nov 12 11:50:47 EST 2018
11:50:47,007 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - *********************************** Test run 1 ***********************************
11:50:47,007 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_ADDRESS_SPACE-8f0c94d4 is correctly stored
11:50:47,007 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-spaces: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; endpoint restapi-enmasse-long4<IP_ADDRESS>.1.nip.io:443;
11:50:48,057 [main] INFO io.enmasse.systemtest.bases.TestBase - Address space '{name=test-create-delete-brokered-space,namespace=test-create-delete-brokered-space,infraUuid=null,type=brokered,plan=brokered-single-broker,annotations={enmasse.io/kc-idp-hint=none}}' doesn't exist and will be created.
11:50:48,057 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address-space: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; body {"apiVersion":"enmasse.io/v1alpha1","kind":"AddressSpace","metadata":{"name":"test-create-delete-brokered-space","annotations":{"enmasse.io/kc-idp-hint":"none"}},"spec":{"type":"brokered","plan":"brokered-single-broker","authenticationService":{"type":"standard"}}}
11:50:49,077 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:00,089 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:51:00,089 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:11,153 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:51:11,153 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:22,173 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:51:22,173 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:23,192 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:51:23,192 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until endpoints for address space 'test-create-delete-brokered-space' will be present...
11:51:23,192 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:24,212 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-spaces: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; endpoint restapi-enmasse-long4<IP_ADDRESS>.1.nip.io:443;
11:51:25,223 [main] INFO io.enmasse.systemtest.bases.TestBase - Address-space 'test-create-delete-brokered-space' endpoints successfully set.
11:51:25,223 [main] INFO io.enmasse.systemtest.bases.TestBase - Address-space successfully created: '{name=test-create-delete-brokered-space,namespace=test-create-delete-brokered-space,infraUuid=lvfd90qm68,type=brokered,plan=brokered-single-broker,annotations={enmasse.io/kc-idp-hint=none},endpoint-messaging={host=messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-messaging={host=messaging-wss-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-console={host=console-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443}}'
11:51:25,223 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation CREATE_ADDRESS_SPACE-8f0c94d4 is correctly stored
11:51:25,223 [main] INFO io.enmasse.systemtest.bases.TestBase - User david:kornelius in address space test-create-delete-brokered-space will be created
11:51:25,223 [main] INFO io.enmasse.systemtest.apiclients.UserApiClient - GET-user-list: path '/apis/user.enmasse.io/v1alpha1/namespaces/enmasse-long406/messagingusers'
11:51:26,392 [main] INFO io.enmasse.systemtest.bases.TestBase - User list for test-create-delete-brokered-space: {"apiVersion":"user.enmasse.io/v1alpha1","kind":"MessagingUserList","items":[{"metadata":{"name":"test-create-delete-brokered-space.****","namespace":"enmasse-long406","creationTimestamp":"2018-11-12T16:50:50.870Z","selfLink":"/apis/user.enmasse.io/v1alpha1/namespaces/enmasse-long406/messagingusers/test-create-delete-brokered-space.****"},"spec":{"username":"****","authentication":{"type":"federated"},"authorization":[{"addresses":["*"],"operations":["view","send","recv"]},{"addresses":["#"],"operations":["view","send","manage","recv"]},{"addresses":[],"operations":["manage"]}]},"apiVersion":"user.enmasse.io/v1alpha1","kind":"MessagingUser"}]}
11:51:26,392 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_USER-f0d746ce is correctly stored
11:51:26,392 [main] INFO io.enmasse.systemtest.apiclients.UserApiClient - POST-user: path /apis/user.enmasse.io/v1alpha1/namespaces/enmasse-long406/messagingusers; body {"metadata":{"name":"test-create-delete-brokered-space.david"},"spec":{"username":"david","authentication":{"type":"password","password":"a29ybmVsaXVz"},"authorization":[{"addresses":["*"],"operations":["send","recv","view"]},{"addresses":[],"operations":["manage"]}]}}
11:51:27,509 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation CREATE_USER-f0d746ce is correctly stored
11:51:27,509 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - Address space created
11:51:27,509 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting router state in namespace enmasse-long406
11:51:27,512 [main] INFO io.enmasse.systemtest.TestUtils - Addresses [{name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0}, {name=test-queue-createdelete-brokered-1, address=test-queue-createdelete-brokered-1}, {name=test-queue-createdelete-brokered-2, address=test-queue-createdelete-brokered-2}, {name=test-queue-createdelete-brokered-3, address=test-queue-createdelete-brokered-3}, {name=test-queue-createdelete-brokered-4, address=test-queue-createdelete-brokered-4}, {name=test-queue-createdelete-brokered-5, address=test-queue-createdelete-brokered-5}, {name=test-queue-createdelete-brokered-6, address=test-queue-createdelete-brokered-6}, {name=test-queue-createdelete-brokered-7, address=test-queue-createdelete-brokered-7}, {name=test-queue-createdelete-brokered-8, address=test-queue-createdelete-brokered-8}, {name=test-queue-createdelete-brokered-9, address=test-queue-createdelete-brokered-9}, {name=test-queue-createdelete-brokered-10, address=test-queue-createdelete-brokered-10}, {name=test-queue-createdelete-brokered-11, address=test-queue-createdelete-brokered-11}, {name=test-queue-createdelete-brokered-12, address=test-queue-createdelete-brokered-12}, {name=test-queue-createdelete-brokered-13, address=test-queue-createdelete-brokered-13}, {name=test-queue-createdelete-brokered-14, address=test-queue-createdelete-brokered-14}, {name=test-queue-createdelete-brokered-15, address=test-queue-createdelete-brokered-15}, {name=test-queue-createdelete-brokered-16, address=test-queue-createdelete-brokered-16}, {name=test-queue-createdelete-brokered-17, address=test-queue-createdelete-brokered-17}, {name=test-queue-createdelete-brokered-18, address=test-queue-createdelete-brokered-18}, {name=test-queue-createdelete-brokered-19, address=test-queue-createdelete-brokered-19}] in address space test-create-delete-brokered-space will be created
11:51:27,512 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_ADDRESS-d69a34af is correctly stored
11:51:27,512 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:51:28,533 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - Creating [{name=test-queue-createdelete-brokered-7, address=test-queue-createdelete-brokered-7}, {name=test-queue-createdelete-brokered-6, address=test-queue-createdelete-brokered-6}, {name=test-queue-createdelete-brokered-19, address=test-queue-createdelete-brokered-19}, {name=test-queue-createdelete-brokered-5, address=test-queue-createdelete-brokered-5}, {name=test-queue-createdelete-brokered-4, address=test-queue-createdelete-brokered-4}, {name=test-queue-createdelete-brokered-9, address=test-queue-createdelete-brokered-9}, {name=test-queue-createdelete-brokered-8, address=test-queue-createdelete-brokered-8}, {name=test-queue-createdelete-brokered-12, address=test-queue-createdelete-brokered-12}, {name=test-queue-createdelete-brokered-11, address=test-queue-createdelete-brokered-11}, {name=test-queue-createdelete-brokered-14, address=test-queue-createdelete-brokered-14}, {name=test-queue-createdelete-brokered-13, address=test-queue-createdelete-brokered-13}, {name=test-queue-createdelete-brokered-3, address=test-queue-createdelete-brokered-3}, {name=test-queue-createdelete-brokered-16, address=test-queue-createdelete-brokered-16}, {name=test-queue-createdelete-brokered-2, address=test-queue-createdelete-brokered-2}, {name=test-queue-createdelete-brokered-15, address=test-queue-createdelete-brokered-15}, {name=test-queue-createdelete-brokered-1, address=test-queue-createdelete-brokered-1}, {name=test-queue-createdelete-brokered-18, address=test-queue-createdelete-brokered-18}, {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0}, {name=test-queue-createdelete-brokered-17, address=test-queue-createdelete-brokered-17}, {name=test-queue-createdelete-brokered-10, address=test-queue-createdelete-brokered-10}]
11:51:28,533 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-7","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-7","type":"queue","plan":"brokered-queue"}}
11:51:29,549 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-6","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-6","type":"queue","plan":"brokered-queue"}}
11:51:30,570 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-19","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-19","type":"queue","plan":"brokered-queue"}}
11:51:31,707 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-5","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-5","type":"queue","plan":"brokered-queue"}}
11:51:32,729 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-4","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-4","type":"queue","plan":"brokered-queue"}}
11:51:33,752 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-9","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-9","type":"queue","plan":"brokered-queue"}}
11:51:34,768 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-8","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-8","type":"queue","plan":"brokered-queue"}}
11:51:35,788 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-12","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-12","type":"queue","plan":"brokered-queue"}}
11:51:36,806 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-11","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-11","type":"queue","plan":"brokered-queue"}}
11:51:37,824 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-14","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-14","type":"queue","plan":"brokered-queue"}}
11:51:38,842 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-13","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-13","type":"queue","plan":"brokered-queue"}}
11:51:39,859 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-3","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-3","type":"queue","plan":"brokered-queue"}}
11:51:40,877 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-16","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-16","type":"queue","plan":"brokered-queue"}}
11:51:41,894 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-2","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-2","type":"queue","plan":"brokered-queue"}}
11:51:42,914 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-15","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-15","type":"queue","plan":"brokered-queue"}}
11:51:43,938 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-1","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-1","type":"queue","plan":"brokered-queue"}}
11:51:44,955 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-18","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-18","type":"queue","plan":"brokered-queue"}}
11:51:45,971 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-0","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-0","type":"queue","plan":"brokered-queue"}}
11:51:46,987 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-17","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-17","type":"queue","plan":"brokered-queue"}}
11:51:48,004 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-10","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-queue-createdelete-brokered-10","type":"queue","plan":"brokered-queue"}}
11:51:49,020 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - Deleting []
11:51:49,020 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:51:50,040 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:51:51,053 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:51:56,053 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:51:57,123 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:02,123 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:03,137 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:08,137 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:09,162 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:14,162 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:15,176 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:20,176 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:21,196 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:26,196 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:27,212 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:32,212 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:33,233 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:38,234 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:39,249 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:44,249 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:45,272 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:50,272 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:51,289 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:52:56,289 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:52:57,316 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:53:02,316 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:53:03,335 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:53:08,335 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:53:09,356 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-queue-createdelete-brokered-0, address=test-queue-createdelete-brokered-0} for ready state
11:53:14,356 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation CREATE_ADDRESS-d69a34af is correctly stored
11:53:14,356 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting configmaps for namespace enmasse-long406
11:53:14,370 [main] INFO io.enmasse.systemtest.TestUtils - Addresses [{name=test-topic-createdelete-brokered-0, address=test-topic-createdelete-brokered-0}, {name=test-topic-createdelete-brokered-1, address=test-topic-createdelete-brokered-1}, {name=test-topic-createdelete-brokered-2, address=test-topic-createdelete-brokered-2}, {name=test-topic-createdelete-brokered-3, address=test-topic-createdelete-brokered-3}, {name=test-topic-createdelete-brokered-4, address=test-topic-createdelete-brokered-4}, {name=test-topic-createdelete-brokered-5, address=test-topic-createdelete-brokered-5}, {name=test-topic-createdelete-brokered-6, address=test-topic-createdelete-brokered-6}, {name=test-topic-createdelete-brokered-7, address=test-topic-createdelete-brokered-7}, {name=test-topic-createdelete-brokered-8, address=test-topic-createdelete-brokered-8}, {name=test-topic-createdelete-brokered-9, address=test-topic-createdelete-brokered-9}, {name=test-topic-createdelete-brokered-10, address=test-topic-createdelete-brokered-10}, {name=test-topic-createdelete-brokered-11, address=test-topic-createdelete-brokered-11}, {name=test-topic-createdelete-brokered-12, address=test-topic-createdelete-brokered-12}, {name=test-topic-createdelete-brokered-13, address=test-topic-createdelete-brokered-13}, {name=test-topic-createdelete-brokered-14, address=test-topic-createdelete-brokered-14}, {name=test-topic-createdelete-brokered-15, address=test-topic-createdelete-brokered-15}, {name=test-topic-createdelete-brokered-16, address=test-topic-createdelete-brokered-16}, {name=test-topic-createdelete-brokered-17, address=test-topic-createdelete-brokered-17}, {name=test-topic-createdelete-brokered-18, address=test-topic-createdelete-brokered-18}, {name=test-topic-createdelete-brokered-19, address=test-topic-createdelete-brokered-19}] in address space test-create-delete-brokered-space will be updated
11:53:14,370 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation APPEND_ADDRESS-7ab4ea6b is correctly stored
11:53:14,370 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-0","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3e08614e-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:46Z","resourceVersion":"43794","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-0"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-1","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3cd251d3-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:44Z","resourceVersion":"43797","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-1"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-10","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3f3e9208-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:49Z","resourceVersion":"43782","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-10"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-11","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3892365c-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:37Z","resourceVersion":"43790","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-11"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-12","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"37f6c704-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:36Z","resourceVersion":"43785","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-12"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-13","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"39c8c088-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:39Z","resourceVersion":"43783","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-13"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-14","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"392d811f-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:38Z","resourceVersion":"43798","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-14"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-15","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3c369f48-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:43Z","resourceVersion":"43793","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-15"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-16","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3aff2fc5-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:41Z","resourceVersion":"43796","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-16"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-17","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3ea37b9d-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:48Z","resourceVersion":"43795","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-17"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-18","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3d6d56cd-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:45Z","resourceVersion":"43789","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-18"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-19","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"34ecae8c-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:31Z","resourceVersion":"43788","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-19"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-2","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3b9ad76c-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:42Z","resourceVersion":"43799","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-2"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-3","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3a63ff97-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:40Z","resourceVersion":"43791","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-3"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-4","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3624b10e-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:33Z","resourceVersion":"43787","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-4"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-5","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"3588a7ca-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:32Z","resourceVersion":"43780","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-5"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-6","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"343eac42-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:30Z","resourceVersion":"43792","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-6"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-7","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"33a35510-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:29Z","resourceVersion":"43781","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-7"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-8","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"375b794b-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:35Z","resourceVersion":"43786","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-8"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.TestUtils - Got address object: {"metadata":{"name":"test-create-delete-brokered-space.test-queue-createdelete-brokered-9","namespace":"enmasse-long406","addressSpace":"test-create-delete-brokered-space","uid":"36bfecb4-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:51:34Z","resourceVersion":"43784","labels":{"addressType":"queue"}},"spec":{"type":"queue","plan":"brokered-queue","address":"test-queue-createdelete-brokered-9"},"status":{"isReady":true,"phase":"Active"}}
11:53:15,384 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-15","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-15","type":"topic","plan":"brokered-topic"}}
11:53:16,405 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-16","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-16","type":"topic","plan":"brokered-topic"}}
11:53:17,428 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-13","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-13","type":"topic","plan":"brokered-topic"}}
11:53:18,449 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-14","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-14","type":"topic","plan":"brokered-topic"}}
11:53:19,470 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-11","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-11","type":"topic","plan":"brokered-topic"}}
11:53:20,487 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-12","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-12","type":"topic","plan":"brokered-topic"}}
11:53:21,504 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-10","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-10","type":"topic","plan":"brokered-topic"}}
11:53:22,520 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-19","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-19","type":"topic","plan":"brokered-topic"}}
11:53:23,536 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-17","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-17","type":"topic","plan":"brokered-topic"}}
11:53:24,552 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-18","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-18","type":"topic","plan":"brokered-topic"}}
11:53:25,573 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-0","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-0","type":"topic","plan":"brokered-topic"}}
11:53:26,590 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-1","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-1","type":"topic","plan":"brokered-topic"}}
11:53:27,608 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-2","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-2","type":"topic","plan":"brokered-topic"}}
11:53:28,628 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-3","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-3","type":"topic","plan":"brokered-topic"}}
11:53:29,648 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-4","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-4","type":"topic","plan":"brokered-topic"}}
11:53:30,667 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-5","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-5","type":"topic","plan":"brokered-topic"}}
11:53:31,684 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-6","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-6","type":"topic","plan":"brokered-topic"}}
11:53:32,701 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-7","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-7","type":"topic","plan":"brokered-topic"}}
11:53:33,719 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-8","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-8","type":"topic","plan":"brokered-topic"}}
11:53:34,736 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses; body: {"apiVersion":"enmasse.io/v1alpha1","kind":"Address","metadata":{"name":"test-create-delete-brokered-space.test-topic-createdelete-brokered-9","addressSpace":"test-create-delete-brokered-space"},"spec":{"address":"test-topic-createdelete-brokered-9","type":"topic","plan":"brokered-topic"}}
11:53:35,755 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:53:36,766 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-addresses: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses;
11:53:37,782 [main] INFO io.enmasse.systemtest.TestUtils - Checking {name=test-topic-createdelete-brokered-0, address=test-topic-createdelete-brokered-0} for ready state
11:53:42,782 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation APPEND_ADDRESS-7ab4ea6b is correctly stored
11:53:42,782 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting configmaps for namespace enmasse-long406
11:53:43,918 [main] INFO io.enmasse.systemtest.amqp.AmqpClientFactory - External endpoint: messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io:443, internal: messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io:443
11:53:44,918 [main] INFO io.enmasse.systemtest.amqp.AmqpClientFactory - External endpoint: messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io:443, internal: messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io:443
11:53:45,296 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-0' opened, sending messages
11:53:46,107 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-0' opened, granting credits
11:53:47,009 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-1' opened, sending messages
11:53:47,685 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-1' opened, granting credits
11:53:48,408 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-2' opened, sending messages
11:53:49,059 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-2' opened, granting credits
11:53:49,768 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-3' opened, sending messages
11:53:50,354 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-3' opened, granting credits
11:53:51,092 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-4' opened, sending messages
11:53:51,693 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-4' opened, granting credits
11:53:52,323 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-5' opened, sending messages
11:53:52,914 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-5' opened, granting credits
11:53:53,578 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-6' opened, sending messages
11:53:54,179 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-6' opened, granting credits
11:53:54,809 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-7' opened, sending messages
11:53:55,354 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-7' opened, granting credits
11:53:55,971 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-8' opened, sending messages
11:53:56,545 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-8' opened, granting credits
11:53:57,173 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-9' opened, sending messages
11:53:57,734 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-9' opened, granting credits
11:53:58,323 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-10' opened, sending messages
11:53:58,886 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-10' opened, granting credits
11:53:59,477 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-11' opened, sending messages
11:54:00,036 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-11' opened, granting credits
11:54:00,634 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-12' opened, sending messages
11:54:01,181 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-12' opened, granting credits
11:54:01,817 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-13' opened, sending messages
11:54:02,367 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-13' opened, granting credits
11:54:03,000 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-14' opened, sending messages
11:54:03,563 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-14' opened, granting credits
11:54:04,172 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-15' opened, sending messages
11:54:04,722 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-15' opened, granting credits
11:54:05,340 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-16' opened, sending messages
11:54:05,892 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-16' opened, granting credits
11:54:06,491 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-17' opened, sending messages
11:54:07,052 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-17' opened, granting credits
11:54:07,668 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-18' opened, sending messages
11:54:08,279 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-18' opened, granting credits
11:54:08,897 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-queue-createdelete-brokered-19' opened, sending messages
11:54:09,470 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-queue-createdelete-brokered-19' opened, granting credits
11:54:10,190 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-0' opened, granting credits
11:54:10,544 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-0' opened, sending messages
11:54:11,379 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-1' opened, granting credits
11:54:11,716 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-1' opened, sending messages
11:54:12,469 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-2' opened, granting credits
11:54:12,791 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-2' opened, sending messages
11:54:13,586 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-3' opened, granting credits
11:54:13,910 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-3' opened, sending messages
11:54:14,695 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-4' opened, granting credits
11:54:15,019 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-4' opened, sending messages
11:54:15,855 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-5' opened, granting credits
11:54:16,180 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-5' opened, sending messages
11:54:17,108 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-6' opened, granting credits
11:54:17,433 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-6' opened, sending messages
11:54:18,147 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-7' opened, granting credits
11:54:18,485 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-7' opened, sending messages
11:54:19,282 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-8' opened, granting credits
11:54:19,608 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-8' opened, sending messages
11:54:20,398 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-9' opened, granting credits
11:54:20,721 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-9' opened, sending messages
11:54:21,487 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-10' opened, granting credits
11:54:21,810 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-10' opened, sending messages
11:54:22,552 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-11' opened, granting credits
11:54:22,879 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-11' opened, sending messages
11:54:23,622 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-12' opened, granting credits
11:54:23,950 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-12' opened, sending messages
11:54:24,704 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-13' opened, granting credits
11:54:25,026 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-13' opened, sending messages
11:54:25,776 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-14' opened, granting credits
11:54:26,114 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-14' opened, sending messages
11:54:26,853 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-15' opened, granting credits
11:54:27,180 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-15' opened, sending messages
11:54:27,880 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-16' opened, granting credits
11:54:28,210 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-16' opened, sending messages
11:54:29,013 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-17' opened, granting credits
11:54:29,341 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-17' opened, sending messages
11:54:30,123 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-18' opened, granting credits
11:54:30,459 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-18' opened, sending messages
11:54:31,212 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Receiver - Receiver link 'test-topic-createdelete-brokered-19' opened, granting credits
11:54:31,545 [vert.x-eventloop-thread-0] INFO io.enmasse.systemtest.amqp.Sender - Sender link 'test-topic-createdelete-brokered-19' opened, sending messages
11:54:31,892 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting configmaps for namespace enmasse-long406
11:54:31,906 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting router state in namespace enmasse-long406
11:54:31,908 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation DELETE_ADDRESS-f6db5b6c is correctly stored
11:54:31,908 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-0
11:54:32,938 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-1
11:54:33,954 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-2
11:54:34,970 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-3
11:54:35,987 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-4
11:54:37,002 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-5
11:54:38,021 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-6
11:54:39,037 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-7
11:54:40,054 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-8
11:54:41,071 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-9
11:54:42,089 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-10
11:54:43,108 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-11
11:54:44,123 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-12
11:54:45,138 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-13
11:54:46,153 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-14
11:54:47,168 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-15
11:54:48,184 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-16
11:54:49,199 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-17
11:54:50,215 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-18
11:54:51,231 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-queue-createdelete-brokered-19
11:54:52,249 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation DELETE_ADDRESS-f6db5b6c is correctly stored
11:54:52,249 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting configmaps for namespace enmasse-long406
11:54:52,257 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting router state in namespace enmasse-long406
11:54:52,259 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation DELETE_ADDRESS-e8e39ae8 is correctly stored
11:54:52,259 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-0
11:54:53,278 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-1
11:54:54,295 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-2
11:54:55,309 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-3
11:54:56,326 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-4
11:54:57,340 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-5
11:54:58,356 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-6
11:54:59,371 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-7
11:55:00,387 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-8
11:55:01,402 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-9
11:55:02,420 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-10
11:55:03,443 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-11
11:55:04,457 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-12
11:55:05,472 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-13
11:55:06,492 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-14
11:55:07,508 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-15
11:55:08,525 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-16
11:55:09,540 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-17
11:55:10,555 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-18
11:55:11,572 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space/addresses/test-create-delete-brokered-space.test-topic-createdelete-brokered-19
11:55:12,591 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation DELETE_ADDRESS-e8e39ae8 is correctly stored
11:55:27,591 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-spaces: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; endpoint restapi-enmasse-long4<IP_ADDRESS>.1.nip.io:443;
11:55:28,611 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation DELETE_ADDRESS_SPACE-3928efe7 is correctly stored
11:55:28,611 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting events in enmasse-long406
11:55:28,768 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Store logs from all terminated pods in namespace 'enmasse-long406'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'address-space-controller-6d6b56bf7b-k6cpr' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'agent.lvfd90qm68-5f64c49ffc-fdsdc' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'api-server-57bb4f6fc-8ktfd' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'broker.lvfd90qm68-5dc885cc65-lmvsk' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'keycloak-74b67f7576-l4scp' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'keycloak-controller-c9bc58fc7-m5q2l' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'none-authservice-6447d8c454-42ptg' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.Kubernetes - pod:'service-broker-578fcf5444-rr6b9' : restart count '0'
11:55:28,772 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting configmaps for namespace enmasse-long406
11:55:28,778 [main] INFO io.enmasse.systemtest.GlobalLogCollector - Collecting router state in namespace enmasse-long406
11:55:28,780 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - DELETE-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:55:29,793 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation DELETE_ADDRESS_SPACE-3928efe7 is correctly stored
11:56:06,917 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - Address space removed
11:56:06,918 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - Client is closed.
11:56:06,918 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - Client is closed.
11:56:06,918 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - ***********************************************************************************
11:57:06,918 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - *********************************** Test run 2 ***********************************
11:57:06,918 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_ADDRESS_SPACE-f00c46e0 is correctly stored
11:57:06,918 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-spaces: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; endpoint restapi-enmasse-long4<IP_ADDRESS>.1.nip.io:443;
11:57:07,950 [main] INFO io.enmasse.systemtest.bases.TestBase - Address space '{name=test-create-delete-brokered-space,namespace=test-create-delete-brokered-space,infraUuid=lvfd90qm68,type=brokered,plan=brokered-single-broker,annotations={enmasse.io/kc-idp-hint=none},endpoint-messaging={host=messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-messaging={host=messaging-wss-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-console={host=console-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443}}' doesn't exist and will be created.
11:57:07,950 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - POST-address-space: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; body {"apiVersion":"enmasse.io/v1alpha1","kind":"AddressSpace","metadata":{"name":"test-create-delete-brokered-space","annotations":{"enmasse.io/kc-idp-hint":"none"}},"spec":{"type":"brokered","plan":"brokered-single-broker","authenticationService":{"type":"standard"},"endpoints":[{"name":"messaging","service":"messaging","servicePort":null},{"name":"messaging-wss","service":"messaging","servicePort":null},{"name":"console","service":"console","servicePort":null}]}}
11:57:08,984 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:57:20,003 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:57:20,003 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:57:31,031 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:57:31,031 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:57:42,048 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:57:42,048 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:57:53,077 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:57:53,077 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:04,094 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:04,094 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:15,111 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:15,111 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:26,129 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:26,129 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:37,146 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:37,146 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:48,166 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:48,166 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:58:59,187 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:58:59,187 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:59:10,203 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:59:10,204 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:59:21,220 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:59:21,220 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:59:32,259 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:59:32,259 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:59:43,277 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:59:43,277 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
11:59:54,295 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
11:59:54,295 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
12:00:05,314 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
12:00:05,314 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
12:00:16,330 [main] INFO io.enmasse.systemtest.TestUtils - Waiting until Address space: 'test-create-delete-brokered-space' will be in ready state
12:00:16,330 [main] WARN io.enmasse.systemtest.marathon.MarathonTestBase - Test run 2 failed with: Address Space test-create-delete-brokered-space is not in Ready state within timeout.
12:00:16,331 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - ***********************************************************************************
12:01:16,331 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - *********************************** Test run 3 ***********************************
12:01:16,331 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_ADDRESS_SPACE-a0860455 is correctly stored
12:01:16,331 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-spaces: path /apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces; endpoint restapi-enmasse-long4<IP_ADDRESS>.1.nip.io:443;
12:01:17,366 [main] INFO io.enmasse.systemtest.apiclients.AddressApiClient - GET-address-space: path '/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space'
12:01:18,377 [main] INFO io.enmasse.systemtest.TestUtils - address space received {"apiVersion":"enmasse.io/v1alpha1","kind":"AddressSpace","metadata":{"name":"test-create-delete-brokered-space","namespace":"enmasse-long406","labels":{"namespace":"enmasse-long406","addressSpaceType":"brokered"},"annotations":{"enmasse.io/realm-name":"enmasse-long406-test-create-delete-brokered-space","enmasse.io/kc-idp-hint":"none","enmasse.io/created-by":"****","enmasse.io/infra-uuid":"ose6ctajqx","enmasse.io/created-by-uid":"8c94a160-e687-11e8-b048-fa163eb98e98","enmasse.io/applied-infra-config":"{\"apiVersion\":\"admin.enmasse.io/v1alpha1\",\"kind\":\"BrokeredInfraConfig\",\"metadata\":{\"creationTimestamp\":\"2018-11-12T14:35:08Z\",\"generation\":1,\"labels\":{\"app\":\"enmasse\"},\"name\":\"default\",\"namespace\":\"enmasse-long406\",\"resourceVersion\":\"4134\",\"selfLink\":\"/apis/admin.enmasse.io/v1alpha1/namespaces/enmasse-long406/brokeredinfraconfigs/default\",\"uid\":\"27469286-e688-11e8-bbb6-fa163eb98e98\"},\"spec\":{\"version\":\"0.24-SNAPSHOT\",\"admin\":{\"resources\":{\"memory\":\"512Mi\"}},\"broker\":{\"resources\":{\"memory\":\"512Mi\",\"storage\":\"5Gi\"},\"addressFullPolicy\":\"FAIL\"}},\"version\":\"0.24-SNAPSHOT\",\"version\":\"0.24-SNAPSHOT\"}"},"uid":"fdf21e8a-e69b-11e8-bbb6-fa163eb98e98","selfLink":"/apis/enmasse.io/v1alpha1/namespaces/enmasse-long406/addressspaces/test-create-delete-brokered-space","creationTimestamp":"2018-11-12T16:57:08Z","resourceVersion":"45073"},"spec":{"type":"brokered","plan":"brokered-single-broker","endpoints":[{"name":"messaging","service":"messaging","cert":{"provider":"selfsigned","secretName":"external-certs-messaging-ose6ctajqx"}},{"name":"messaging-wss","service":"messaging","cert":{"provider":"selfsigned","secretName":"external-certs-messaging-ose6ctajqx"}},{"name":"console","service":"console","cert":{"provider":"selfsigned","secretName":"external-certs-console-ose6ctajqx"}}],"authenticationService":{"type":"standard","details":{}}},"status":{"isReady":false,"messages":["Standard authentication service is not configured with realm enmasse-long406-test-create-delete-brokered-space"]}}
12:01:18,377 [main] INFO io.enmasse.systemtest.bases.TestBase - Address space '{name=test-create-delete-brokered-space,namespace=test-create-delete-brokered-space,infraUuid=lvfd90qm68,type=brokered,plan=brokered-single-broker,annotations={enmasse.io/kc-idp-hint=none},endpoint-messaging={host=messaging-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-messaging={host=messaging-wss-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443},endpoint-console={host=console-lvfd90qm68-enmasse-long4<IP_ADDRESS>.1.nip.io,port=443}}' already exists.
12:01:18,377 [main] INFO io.enmasse.systemtest.bases.TestBase - Address-space 'test-create-delete-brokered-space' endpoints successfully set.
12:01:18,377 [main] INFO io.enmasse.systemtest.bases.TestBase - Address-space successfully created: '{name=test-create-delete-brokered-space,namespace=test-create-delete-brokered-space,infraUuid=ose6ctajqx,type=brokered,plan=brokered-single-broker,annotations={enmasse.io/kc-idp-hint=none},endpoint-messaging={host=null,port=0},endpoint-messaging={host=null,port=0},endpoint-console={host=null,port=0}}'
12:01:18,377 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation CREATE_ADDRESS_SPACE-a0860455 is correctly stored
12:01:18,377 [main] INFO io.enmasse.systemtest.bases.TestBase - User david:kornelius in address space test-create-delete-brokered-space will be created
12:01:18,377 [main] INFO io.enmasse.systemtest.apiclients.UserApiClient - GET-user-list: path '/apis/user.enmasse.io/v1alpha1/namespaces/enmasse-long406/messagingusers'
12:01:19,426 [main] INFO io.enmasse.systemtest.bases.TestBase - User list for test-create-delete-brokered-space: {"apiVersion":"user.enmasse.io/v1alpha1","kind":"MessagingUserList","items":[]}
12:01:19,427 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - Start time of operation CREATE_USER-461fb045 is correctly stored
12:01:19,427 [main] INFO io.enmasse.systemtest.apiclients.UserApiClient - POST-user: path /apis/user.enmasse.io/v1alpha1/namespaces/enmasse-long406/messagingusers; body {"metadata":{"name":"test-create-delete-brokered-space.david"},"spec":{"username":"david","authentication":{"type":"password","password":"a29ybmVsaXVz"},"authorization":[{"addresses":["*"],"operations":["send","recv","view"]},{"addresses":[],"operations":["manage"]}]}}
java.lang.RuntimeException: Status 503 body: {"statusCode":503,"apiVersion":"v1","kind":"Status","status":"Failure","code":503,"reason":"Service Unavailable","message":"Timed out waiting for realm enmasse-long406-test-create-delete-brokered-space to exist"}
	at io.enmasse.systemtest.apiclients.ApiClient.responseHandler(ApiClient.java:82)
	at io.enmasse.systemtest.apiclients.UserApiClient.lambda$null$6(UserApiClient.java:120)
	at io.vertx.ext.web.client.impl.HttpContext.lambda$null$0(HttpContext.java:125)
	at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:339)
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:404)
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:465)
	at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:884)
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
	at java.lang.Thread.run(Thread.java:748)
12:01:30,495 [vert.x-eventloop-thread-0] ERROR io.enmasse.systemtest.apiclients.ApiClient - expected-code: 201, response-code: 503, body: {"statusCode":503,"apiVersion":"v1","kind":"Status","status":"Failure","code":503,"reason":"Service Unavailable","message":"Timed out waiting for realm enmasse-long406-test-create-delete-brokered-space to exist"}
12:01:30,496 [main] INFO io.enmasse.systemtest.timemeasuring.TimeMeasuringSystem - End time of operation CREATE_USER-461fb045 is correctly stored
12:01:30,496 [main] WARN io.enmasse.systemtest.marathon.MarathonTestBase - Test run 3 failed with: java.lang.RuntimeException: Status 503 body: {"statusCode":503,"apiVersion":"v1","kind":"Status","status":"Failure","code":503,"reason":"Service Unavailable","message":"Timed out waiting for realm enmasse-long406-test-create-delete-brokered-space to exist"}
12:01:30,496 [main] INFO io.enmasse.systemtest.marathon.MarathonTestBase - ***********************************************************************************

@kornys It looks like tests don't properly wait for address space to disappear. There is an 'already exists' in there, which should really be a test failure in the marathon tests, right?