2026-05-18 03:17:26,956 - INFO - Domain Default found not creating
2026-05-18 03:17:27,153 - INFO - Project ctest-SecurityGroupBasicRegressionTests1-43022588 not found, creating it
2026-05-18 03:17:27,816 - INFO - Created Project:ctest-SecurityGroupBasicRegressionTests1-43022588, ID : a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:29,414 - INFO - ================================================================================
2026-05-18 03:17:29,414 - INFO - STARTING TEST : test_sec_group_basic
2026-05-18 03:17:29,414 - INFO - TEST DESCRIPTION :
Description: Test basic SG features
1. Security group create and delete
2. Create security group with custom rules and then update it for tcp
3. Launch VM with custom created security group and verify
4. Remove secuity group association with VM
5. Add back custom security group to VM and verify
6. Try to delete security group with association to VM. It should fail.
7. Test with ping, which should fail
8. Test with TCP which should pass
9. Update the rules to allow icmp, ping should pass now.
2026-05-18 03:17:30,715 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '2'}, '10.0.0.130': {'10.20.0.25': '0', '10.20.0.129': '3'}} with
2026-05-18 03:17:30,715 - INFO - Initial checks done. Running the testcase now
2026-05-18 03:17:30,715 - INFO -
2026-05-18 03:17:31,111 - INFO - Creating Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580']
2026-05-18 03:17:32,360 - DEBUG - Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580'] created succesfully
2026-05-18 03:17:32,360 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:32,673 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:32,707 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:32,726 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:32,832 - DEBUG - Requesting: http://10.0.0.23:8082/security-group/63502427-e78b-427b-a2a2-8c953f97dc67
2026-05-18 03:17:32,843 - INFO - Security group ctest-test_sec_group-14291580 found in the API Server
2026-05-18 03:17:32,843 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:32,853 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:32,865 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:32,969 - DEBUG - Requesting: http://10.0.0.23:8082/access-control-lists
2026-05-18 03:17:32,979 - INFO - Validated that ACLs for Security group ctest-test_sec_group-14291580 are present in API Server
2026-05-18 03:17:32,986 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.23
2026-05-18 03:17:32,993 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.23
2026-05-18 03:17:33,000 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.22
2026-05-18 03:17:33,006 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.22
2026-05-18 03:17:33,012 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.240
2026-05-18 03:17:33,019 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.240
2026-05-18 03:17:33,019 - INFO - Validated SG ctest-test_sec_group-14291580 in Control nodes
2026-05-18 03:17:33,019 - INFO - Deleting Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580']
2026-05-18 03:17:33,125 - INFO - Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580'] deleted successfully.
2026-05-18 03:17:33,126 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:33,136 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:33,149 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:33,248 - INFO - Security group ctest-test_sec_group-14291580 removed from the API Server
2026-05-18 03:17:33,248 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:33,257 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:33,271 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:33,372 - DEBUG - Requesting: http://10.0.0.23:8082/access-control-lists
2026-05-18 03:17:33,382 - DEBUG - ACLs for Security group ctest-test_sec_group-14291580 removed from the API Server
2026-05-18 03:17:33,392 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.23
2026-05-18 03:17:33,402 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.23
2026-05-18 03:17:33,409 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.22
2026-05-18 03:17:33,415 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.22
2026-05-18 03:17:33,421 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.240
2026-05-18 03:17:33,427 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.240
2026-05-18 03:17:33,427 - INFO - Validated that SG ctest-test_sec_group-14291580 is not in control nodes
2026-05-18 03:17:33,434 - INFO - Creating Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580']
2026-05-18 03:17:34,553 - DEBUG - Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580'] created succesfully
2026-05-18 03:17:34,553 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:34,567 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:34,582 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:34,676 - DEBUG - Requesting: http://10.0.0.23:8082/security-group/22a614d6-926c-4410-912c-d8d31a4927dc
2026-05-18 03:17:34,694 - INFO - Security group ctest-test_sec_group-14291580 found in the API Server
2026-05-18 03:17:34,695 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:34,703 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:34,719 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:34,821 - DEBUG - Requesting: http://10.0.0.23:8082/access-control-lists
2026-05-18 03:17:34,830 - INFO - Validated that ACLs for Security group ctest-test_sec_group-14291580 are present in API Server
2026-05-18 03:17:34,839 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.23
2026-05-18 03:17:34,848 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.23
2026-05-18 03:17:34,854 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.22
2026-05-18 03:17:34,861 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.22
2026-05-18 03:17:34,868 - INFO - Validated that Security group ctest-test_sec_group-14291580 is found in control node 10.0.0.240
2026-05-18 03:17:34,874 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs found in the control node 10.0.0.240
2026-05-18 03:17:34,875 - INFO - Validated SG ctest-test_sec_group-14291580 in Control nodes
2026-05-18 03:17:34,875 - DEBUG - Replace all the rules of this security group ctest-test_sec_group-14291580 with the new rules
2026-05-18 03:17:34,875 - DEBUG - [{'protocol': 'tcp', 'dst_addresses': [{'subnet': {'ip_prefix': '25.227.25.64', 'ip_prefix_len': '26'}}], 'dst_ports': [{'start_port': 0, 'end_port': -1}], 'src_ports': [{'start_port': 0, 'end_port': -1}], 'src_addresses': [{'security_group': 'local'}]}, {'protocol': 'tcp', 'src_addresses': [{'subnet': {'ip_prefix': '25.227.25.64', 'ip_prefix_len': '26'}}], 'dst_ports': [{'start_port': 0, 'end_port': -1}], 'src_ports': [{'start_port': 0, 'end_port': -1}], 'dst_addresses': [{'security_group': 'local'}]}]
2026-05-18 03:17:35,795 - DEBUG - Response for create_network : {'network': {'id': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'tenant_id': 'a0a39034bc3449e7b5be2b5e2790ca33', 'project_id': 'a0a39034bc3449e7b5be2b5e2790ca33', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'port_security_enabled': True, 'description': ''}}
2026-05-18 03:17:36,062 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c740c16c-9ff0-4ac5-b19d-5ad862921785', 'name': '', 'tenant_id': 'a0a39034bc3449e7b5be2b5e2790ca33', 'network_id': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'ip_version': 4, 'cidr': '25.227.25.64/26', 'allocation_pools': [{'start': '25.227.25.66', 'end': '25.227.25.126'}], 'gateway_ip': '25.227.25.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '25.227.25.66', 'tags': [], 'project_id': 'a0a39034bc3449e7b5be2b5e2790ca33'}}
2026-05-18 03:17:36,092 - INFO - Created VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:17:36,110 - DEBUG - VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 UUID is 251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,203 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:36,209 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:36,222 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:36,333 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,357 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,368 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:17:36,382 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:17:36,390 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0ecfeb3c-4e96-4ab3-9749-880a73fc8e46
2026-05-18 03:17:36,399 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 03:17:36,399 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,410 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:17:36,428 - INFO - Verified VN network id 12 for VN 251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,428 - INFO - Verifications in API Server for VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 passed
2026-05-18 03:17:36,428 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,441 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:17:36,451 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:17:36,460 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0ecfeb3c-4e96-4ab3-9749-880a73fc8e46
2026-05-18 03:17:36,474 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2673479119244971858', 'uuid-lslong': '10880435613558526653'}, 'enable': 'true', 'created': '2026-05-18T03:17:35', 'last-modified': '2026-05-18T03:17:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484780'}
2026-05-18 03:17:36,483 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 03:17:36,490 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2673479119244971858', 'uuid-lslong': '10880435613558526653'}, 'enable': 'true', 'created': '2026-05-18T03:17:35', 'last-modified': '2026-05-18T03:17:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.503617'}
2026-05-18 03:17:36,497 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 03:17:36,504 - DEBUG - Control-node 10.0.0.240 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2673479119244971858', 'uuid-lslong': '10880435613558526653'}, 'enable': 'true', 'created': '2026-05-18T03:17:35', 'last-modified': '2026-05-18T03:17:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.524587'}
2026-05-18 03:17:36,511 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 03:17:36,511 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 passed
2026-05-18 03:17:36,511 - DEBUG - ====Verifying policy data for ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 in API_Server ======
2026-05-18 03:17:36,511 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:17:36,518 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:17:36,540 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:17:36,642 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd
2026-05-18 03:17:36,658 - DEBUG - =>VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 has no policy to be verified
2026-05-18 03:17:36,658 - DEBUG - Verifying the vn in opserver
2026-05-18 03:17:36,658 - DEBUG - Verifying the default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 virtual network link through opserver 10.0.0.23
2026-05-18 03:17:36,659 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-05-18 03:17:36,969 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-05-18 03:17:37,039 - DEBUG - vn link and name as {'name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322?flat'}
2026-05-18 03:17:37,039 - DEBUG - Verifying the default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 virtual network link through opserver 10.0.0.22
2026-05-18 03:17:37,039 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-18 03:17:37,352 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-05-18 03:17:37,418 - DEBUG - vn link and name as {'name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322?flat'}
2026-05-18 03:17:37,418 - DEBUG - Verifying the default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 virtual network link through opserver 10.0.0.240
2026-05-18 03:17:37,419 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2026-05-18 03:17:37,741 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-networks
2026-05-18 03:17:37,818 - DEBUG - vn link and name as {'name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-network/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322?flat'}
2026-05-18 03:17:37,818 - INFO - Validated that VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is found in opserver
2026-05-18 03:17:37,844 - DEBUG - Do not have enough data to verify VN in agent
2026-05-18 03:17:37,855 - DEBUG - VRF ids for VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322: {}
2026-05-18 03:17:37,988 - DEBUG - Services list from nova: [, , , ]
2026-05-18 03:17:39,134 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5792-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5792-2)
2026-05-18 03:17:40,583 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5792-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5792-1)
2026-05-18 03:17:40,687 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-05-18 03:17:45,779 - DEBUG - VM is in ACTIVE state now
2026-05-18 03:17:45,779 - INFO - VM name : ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:17:45,866 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 ID is e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:45,962 - DEBUG - VM is in ACTIVE state now
2026-05-18 03:17:45,989 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 launched on Node an-jenkins-deploy-platform-ansible-os-5792-2
2026-05-18 03:17:46,077 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:46,383 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:46,418 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:46,426 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:17:46,438 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:46,446 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:17:46,456 - DEBUG - Requesting: http://10.0.0.240:8082/instance-ip/9cf0145e-c8f8-416c-ae90-562208550999
2026-05-18 03:17:46,466 - DEBUG - Verifying in api server 10.0.0.240
2026-05-18 03:17:46,466 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:17:46,475 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:17:46,486 - DEBUG - Requesting: http://10.0.0.240:8082/instance-ip/9cf0145e-c8f8-416c-ae90-562208550999
2026-05-18 03:17:46,495 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 verfication in all API Servers passed
2026-05-18 03:17:46,513 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 Tap interface: {'index': '4', 'name': 'tap210cd262-16', 'uuid': '210cd262-1696-4720-9e92-93601a51722a', 'vrf_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'vm_uuid': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-60675943', 'ip_addr': '25.227.25.67', 'mac_addr': '02:21:0c:d2:62:16', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '77', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a', 'sg_uuid_list': ['22a614d6-926c-4410-912c-d8d31a4927dc'], 'static_route_list': None, 'vm_project_uuid': 'a0a39034-bc34-49e7-b5be-2b5e2790ca33', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-18 03:17:46,513 - DEBUG - Agent 10.0.0.130 vrf name: default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:17:46,519 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-05-18 03:17:46,601 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 : Tap interface tap210cd262-16 is set to Active
2026-05-18 03:17:46,601 - DEBUG - Tap interface tap210cd262-16 detail : {'index': '4', 'name': 'tap210cd262-16', 'uuid': '210cd262-1696-4720-9e92-93601a51722a', 'vrf_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'vm_uuid': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-60675943', 'ip_addr': '25.227.25.67', 'mac_addr': '02:21:0c:d2:62:16', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '77', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a', 'sg_uuid_list': ['22a614d6-926c-4410-912c-d8d31a4927dc'], 'static_route_list': None, 'vm_project_uuid': 'a0a39034-bc34-49e7-b5be-2b5e2790ca33', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-18 03:17:46,716 - DEBUG - Starting Layer 2 verification in Agent
2026-05-18 03:17:46,733 - DEBUG - Layer 2 path is seen for VM MAC 02:21:0c:d2:62:16 in agent 10.0.0.130
2026-05-18 03:17:46,733 - DEBUG - Active layer 2 route in agent is present for VMI tap210cd262-16
2026-05-18 03:17:46,734 - DEBUG - L2 label(36) matches bw route table and interface table
2026-05-18 03:17:46,741 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is found in Agent of node 10.0.0.65
2026-05-18 03:17:46,877 - DEBUG - VRF IDs of VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is consistent in agent 10.0.0.65
2026-05-18 03:17:46,878 - DEBUG - Route for VM IP ['25.227.25.67'] is consistent in agent 10.0.0.65
2026-05-18 03:17:46,878 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 verification for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 in Agent 10.0.0.65 passed
2026-05-18 03:17:46,990 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65
2026-05-18 03:17:47,002 - DEBUG - Route for VM MAC 02:21:0c:d2:62:16 is consistent in agent 10.0.0.65
2026-05-18 03:17:47,009 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is found in Agent of node 10.0.0.130
2026-05-18 03:17:47,141 - DEBUG - VRF IDs of VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is consistent in agent 10.0.0.130
2026-05-18 03:17:47,141 - DEBUG - Route for VM IP ['25.227.25.67'] is consistent in agent 10.0.0.130
2026-05-18 03:17:47,141 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 verification for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 in Agent 10.0.0.130 passed
2026-05-18 03:17:47,255 - DEBUG - Starting all layer 2 verification in agent 10.0.0.130
2026-05-18 03:17:47,270 - DEBUG - Route for VM MAC 02:21:0c:d2:62:16 is consistent in agent 10.0.0.130
2026-05-18 03:17:50,461 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2026-05-18 03:17:50,462 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:17:50,479 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:17:54,559 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-05-18 03:17:54,559 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:17:54,573 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:17:58,655 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-05-18 03:17:58,655 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:17:58,672 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:02,746 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-05-18 03:18:02,746 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:18:02,762 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:06,845 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-05-18 03:18:06,845 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:18:06,859 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:10,944 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-05-18 03:18:10,944 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:18:10,960 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:15,039 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-05-18 03:18:15,039 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:18:15,053 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:19,136 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2026-05-18 03:18:19,136 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 failed!
2026-05-18 03:18:19,150 - DEBUG - Gateway for vn default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is 25.227.25.65 and allocation pool is NOT set
2026-05-18 03:18:21,222 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.65 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.45 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.452/4.553/5.654/1.101 ms')
2026-05-18 03:18:21,222 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 passed
2026-05-18 03:18:21,222 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 verifications in Compute nodes passed
2026-05-18 03:18:21,430 - DEBUG - Validated VM route 25.227.25.67 in vrouter of 10.0.0.65
2026-05-18 03:18:21,434 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: 10.20.0.192, 32; Got 10.20.0.192,32
2026-05-18 03:18:21,435 - DEBUG - Validated VM route 25.227.25.67 in vrouter of 10.0.0.65
2026-05-18 03:18:21,552 - DEBUG - Validated VM route 25.227.25.67 in vrouter of 10.0.0.130
2026-05-18 03:18:21,557 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-05-18 03:18:21,557 - INFO - Validated routes of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 in all vrouters
2026-05-18 03:18:22,011 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2026-05-18 03:18:22,144 - DEBUG - Layer2 route found for VM MAC 02:21:0c:d2:62:16 in Control-node 10.0.0.23
2026-05-18 03:18:22,145 - DEBUG - L2 Label for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36
2026-05-18 03:18:22,145 - DEBUG - Starting all layer2 verification in 10.0.0.240 Control Node
2026-05-18 03:18:22,273 - DEBUG - Layer2 route found for VM MAC 02:21:0c:d2:62:16 in Control-node 10.0.0.240
2026-05-18 03:18:22,273 - DEBUG - L2 Label for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 same between Control-node 10.0.0.240 and Agent, Expected: 36, Seen: 36
2026-05-18 03:18:22,273 - INFO - Verification in Control-nodes for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 passed
2026-05-18 03:18:22,273 - DEBUG - Verifying the vm in opserver
2026-05-18 03:18:22,273 - DEBUG - Verifying in collector 10.0.0.240 ...
2026-05-18 03:18:22,273 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat
2026-05-18 03:18:22,347 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a?flat
2026-05-18 03:18:22,418 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a?flat
2026-05-18 03:18:22,503 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat
2026-05-18 03:18:22,582 - DEBUG - Verifying vm in vn uve
2026-05-18 03:18:22,582 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a?flat
2026-05-18 03:18:22,601 - DEBUG - VM uve shows interface as {'l2_active': True, 'uuid': '210cd262-1696-4720-9e92-93601a51722a', 'ip6_active': False, 'port_mirror_enabled': False, 'tx_vlan': 65535, 'gateway': '25.227.25.65', 'vm_uuid': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'virtual_network': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', '__T': 1779074273555571, 'vhostuser_mode': 0, 'ip4_active': True, 'ip_address': '25.227.25.67', 'ip6_address': '::', 'is_health_check_active': True, 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-60675943', 'admin_state': True, 'mac_address': '02:21:0c:d2:62:16', 'label': 32, 'fixed_ip4_list': ['25.227.25.67'], 'vn_uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'active': True, 'rx_vlan': 65535}
2026-05-18 03:18:22,601 - DEBUG - VM uve shows ip address as ['25.227.25.67', '::']
2026-05-18 03:18:22,601 - DEBUG - VM uve shows virtual network as default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:22,601 - INFO - Verifying through opserver in 10.0.0.23
2026-05-18 03:18:22,601 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322?flat
2026-05-18 03:18:22,626 - DEBUG - expected vm list e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:18:22,626 - DEBUG - Extracted vm list ['e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3']
2026-05-18 03:18:22,626 - INFO - VM e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is present in default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:22,626 - DEBUG - Verifying vm in vrouter uve
2026-05-18 03:18:22,626 - DEBUG - Getting info from collector 10.0.0.240..
2026-05-18 03:18:22,627 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat
2026-05-18 03:18:22,642 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5792-2']
2026-05-18 03:18:22,642 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5792-2:Compute:contrail-vrouter-agent:0?flat
2026-05-18 03:18:22,679 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5792-2?flat
2026-05-18 03:18:22,764 - DEBUG - VM e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-2 uve
2026-05-18 03:18:22,764 - DEBUG - Validated that VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 is in Vrouter an-jenkins-deploy-platform-ansible-os-5792-2 UVE
2026-05-18 03:18:22,764 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-05-18 03:18:22,771 - DEBUG - Expected tap interface of VM uuid e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:18:22,772 - DEBUG - Expected VN of VM uuid e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:22,772 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5792-2:Compute:contrail-vrouter-agent:0?flat
2026-05-18 03:18:22,819 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5792-2?flat
2026-05-18 03:18:22,912 - DEBUG - VM e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-2 uve
2026-05-18 03:18:22,912 - INFO - tap interface default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:210cd262-1696-4720-9e92-93601a51722a of vm e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-2 uve
2026-05-18 03:18:22,912 - DEBUG - Connected network default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 of vm e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-2 uve
2026-05-18 03:18:22,912 - DEBUG - Verifying the e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 virtual network link through opserver 10.0.0.23
2026-05-18 03:18:22,912 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-05-18 03:18:22,928 - DEBUG - VM link and name as {'name': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat'}
2026-05-18 03:18:22,928 - DEBUG - Verifying the e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 virtual network link through opserver 10.0.0.22
2026-05-18 03:18:22,928 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-05-18 03:18:22,941 - DEBUG - VM link and name as {'name': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat'}
2026-05-18 03:18:22,941 - DEBUG - Verifying the e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3 virtual network link through opserver 10.0.0.240
2026-05-18 03:18:22,941 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machines
2026-05-18 03:18:22,956 - DEBUG - VM link and name as {'name': 'e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3?flat'}
2026-05-18 03:18:22,956 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 validations in Opserver passed
2026-05-18 03:18:22,956 - INFO - Waiting for VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 to be up..
2026-05-18 03:18:22,956 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:22,956 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:18:22,964 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:18:24,129 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.03 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.511 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.511/1.269/2.027/0.758 ms')
2026-05-18 03:18:24,129 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 passed
2026-05-18 03:18:24,201 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:24,201 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943, IP 25.227.25.67, Port 22
2026-05-18 03:18:24,269 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:24,364 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:18:29,365 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:29,365 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943, IP 25.227.25.67, Port 22
2026-05-18 03:18:29,431 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:29,522 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:18:34,523 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:34,523 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943, IP 25.227.25.67, Port 22
2026-05-18 03:18:34,593 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:34,696 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:18:39,697 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:39,697 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943, IP 25.227.25.67, Port 22
2026-05-18 03:18:39,871 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943 is ready for SSH connections
2026-05-18 03:18:39,963 - DEBUG - VM is in ACTIVE state now
2026-05-18 03:18:39,964 - INFO - VM name : ctest-SecurityGroupBasicRegressionTests1-43022588-23709050
2026-05-18 03:18:40,056 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 ID is dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:40,151 - DEBUG - VM is in ACTIVE state now
2026-05-18 03:18:40,151 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 launched on Node an-jenkins-deploy-platform-ansible-os-5792-1
2026-05-18 03:18:40,244 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:40,255 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:40,265 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1
2026-05-18 03:18:40,278 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:40,286 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1
2026-05-18 03:18:40,298 - DEBUG - Requesting: http://10.0.0.240:8082/instance-ip/ee2ffd97-5fbb-4e9b-9091-8b1e14e42464
2026-05-18 03:18:40,308 - DEBUG - Verifying in api server 10.0.0.240
2026-05-18 03:18:40,308 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:40,315 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1
2026-05-18 03:18:40,326 - DEBUG - Requesting: http://10.0.0.240:8082/instance-ip/ee2ffd97-5fbb-4e9b-9091-8b1e14e42464
2026-05-18 03:18:40,337 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 verfication in all API Servers passed
2026-05-18 03:18:40,360 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 Tap interface: {'index': '4', 'name': 'tapf6e1a577-e1', 'uuid': 'f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1', 'vrf_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'vm_uuid': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-23709050', 'ip_addr': '25.227.25.68', 'mac_addr': '02:f6:e1:a5:77:e1', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '65', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1', 'sg_uuid_list': ['22a614d6-926c-4410-912c-d8d31a4927dc'], 'static_route_list': None, 'vm_project_uuid': 'a0a39034-bc34-49e7-b5be-2b5e2790ca33', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-18 03:18:40,360 - DEBUG - Agent 10.0.0.65 vrf name: default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:40,369 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.18:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-05-18 03:18:40,462 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 : Tap interface tapf6e1a577-e1 is set to Active
2026-05-18 03:18:40,462 - DEBUG - Tap interface tapf6e1a577-e1 detail : {'index': '4', 'name': 'tapf6e1a577-e1', 'uuid': 'f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1', 'vrf_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'vm_uuid': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-23709050', 'ip_addr': '25.227.25.68', 'mac_addr': '02:f6:e1:a5:77:e1', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '65', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1', 'sg_uuid_list': ['22a614d6-926c-4410-912c-d8d31a4927dc'], 'static_route_list': None, 'vm_project_uuid': 'a0a39034-bc34-49e7-b5be-2b5e2790ca33', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-18 03:18:40,583 - DEBUG - Starting Layer 2 verification in Agent
2026-05-18 03:18:40,601 - DEBUG - Layer 2 path is seen for VM MAC 02:f6:e1:a5:77:e1 in agent 10.0.0.65
2026-05-18 03:18:40,601 - DEBUG - Active layer 2 route in agent is present for VMI tapf6e1a577-e1
2026-05-18 03:18:40,601 - DEBUG - L2 label(36) matches bw route table and interface table
2026-05-18 03:18:40,613 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is found in Agent of node 10.0.0.65
2026-05-18 03:18:40,713 - DEBUG - VRF IDs of VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is consistent in agent 10.0.0.65
2026-05-18 03:18:40,713 - DEBUG - Route for VM IP ['25.227.25.68'] is consistent in agent 10.0.0.65
2026-05-18 03:18:40,713 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 verification for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 in Agent 10.0.0.65 passed
2026-05-18 03:18:40,832 - DEBUG - Starting all layer 2 verification in agent 10.0.0.65
2026-05-18 03:18:40,846 - DEBUG - Route for VM MAC 02:f6:e1:a5:77:e1 is consistent in agent 10.0.0.65
2026-05-18 03:18:40,852 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is found in Agent of node 10.0.0.130
2026-05-18 03:18:40,991 - DEBUG - VRF IDs of VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is consistent in agent 10.0.0.130
2026-05-18 03:18:40,991 - DEBUG - Route for VM IP ['25.227.25.68'] is consistent in agent 10.0.0.130
2026-05-18 03:18:40,991 - DEBUG - VN default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 verification for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 in Agent 10.0.0.130 passed
2026-05-18 03:18:41,123 - DEBUG - Starting all layer 2 verification in agent 10.0.0.130
2026-05-18 03:18:41,139 - DEBUG - Route for VM MAC 02:f6:e1:a5:77:e1 is consistent in agent 10.0.0.130
2026-05-18 03:18:42,324 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.73 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=4.07 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 4.069/5.399/6.729/1.330 ms')
2026-05-18 03:18:42,324 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 passed
2026-05-18 03:18:42,325 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 verifications in Compute nodes passed
2026-05-18 03:18:42,624 - DEBUG - Validated VM route 25.227.25.68 in vrouter of 10.0.0.65
2026-05-18 03:18:42,632 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-05-18 03:18:42,743 - DEBUG - Validated VM route 25.227.25.68 in vrouter of 10.0.0.130
2026-05-18 03:18:42,748 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: 10.20.0.18, 32; Got 10.20.0.18,32
2026-05-18 03:18:42,748 - DEBUG - Validated VM route 25.227.25.68 in vrouter of 10.0.0.130
2026-05-18 03:18:42,748 - INFO - Validated routes of VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 in all vrouters
2026-05-18 03:18:43,211 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2026-05-18 03:18:43,356 - DEBUG - Layer2 route found for VM MAC 02:f6:e1:a5:77:e1 in Control-node 10.0.0.23
2026-05-18 03:18:43,356 - DEBUG - L2 Label for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36
2026-05-18 03:18:43,356 - DEBUG - Starting all layer2 verification in 10.0.0.240 Control Node
2026-05-18 03:18:43,490 - DEBUG - Layer2 route found for VM MAC 02:f6:e1:a5:77:e1 in Control-node 10.0.0.240
2026-05-18 03:18:43,490 - DEBUG - L2 Label for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 same between Control-node 10.0.0.240 and Agent, Expected: 36, Seen: 36
2026-05-18 03:18:43,490 - INFO - Verification in Control-nodes for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 passed
2026-05-18 03:18:43,490 - DEBUG - Verifying the vm in opserver
2026-05-18 03:18:43,490 - DEBUG - Verifying in collector 10.0.0.240 ...
2026-05-18 03:18:43,491 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat
2026-05-18 03:18:43,508 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1?flat
2026-05-18 03:18:43,530 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1?flat
2026-05-18 03:18:43,549 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat
2026-05-18 03:18:43,566 - DEBUG - Verifying vm in vn uve
2026-05-18 03:18:43,566 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1?flat
2026-05-18 03:18:43,589 - DEBUG - VM uve shows interface as {'ip_address': '25.227.25.68', 'label': 32, 'ip6_address': '::', '__T': 1779074274145640, 'gateway': '25.227.25.65', 'uuid': 'f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1', 'rx_vlan': 65535, 'vn_uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'l2_active': True, 'is_health_check_active': True, 'ip6_active': False, 'active': True, 'ip4_active': True, 'vhostuser_mode': 0, 'admin_state': True, 'fixed_ip4_list': ['25.227.25.68'], 'vm_name': 'ctest-SecurityGroupBasicRegressionTests1-43022588-23709050', 'virtual_network': 'default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322', 'port_mirror_enabled': False, 'vm_uuid': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'tx_vlan': 65535, 'mac_address': '02:f6:e1:a5:77:e1'}
2026-05-18 03:18:43,589 - DEBUG - VM uve shows ip address as ['25.227.25.68', '::']
2026-05-18 03:18:43,589 - DEBUG - VM uve shows virtual network as default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:43,589 - INFO - Verifying through opserver in 10.0.0.23
2026-05-18 03:18:43,589 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322?flat
2026-05-18 03:18:43,618 - DEBUG - expected vm list dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:43,618 - DEBUG - Extracted vm list ['dd9c7899-29b5-46c5-bbdb-badc1011d043']
2026-05-18 03:18:43,618 - INFO - VM dd9c7899-29b5-46c5-bbdb-badc1011d043 is present in default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:43,618 - DEBUG - Verifying vm in vrouter uve
2026-05-18 03:18:43,618 - DEBUG - Getting info from collector 10.0.0.240..
2026-05-18 03:18:43,618 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat
2026-05-18 03:18:43,639 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5792-1']
2026-05-18 03:18:43,639 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5792-1:Compute:contrail-vrouter-agent:0?flat
2026-05-18 03:18:43,679 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5792-1?flat
2026-05-18 03:18:43,715 - DEBUG - VM dd9c7899-29b5-46c5-bbdb-badc1011d043 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-1 uve
2026-05-18 03:18:43,715 - DEBUG - Validated that VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 is in Vrouter an-jenkins-deploy-platform-ansible-os-5792-1 UVE
2026-05-18 03:18:43,715 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-05-18 03:18:43,723 - DEBUG - Expected tap interface of VM uuid dd9c7899-29b5-46c5-bbdb-badc1011d043 is default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1
2026-05-18 03:18:43,723 - DEBUG - Expected VN of VM uuid dd9c7899-29b5-46c5-bbdb-badc1011d043 is default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:18:43,723 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5792-1:Compute:contrail-vrouter-agent:0?flat
2026-05-18 03:18:43,762 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5792-1?flat
2026-05-18 03:18:43,800 - DEBUG - VM dd9c7899-29b5-46c5-bbdb-badc1011d043 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-1 uve
2026-05-18 03:18:43,800 - INFO - tap interface default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1 of vm dd9c7899-29b5-46c5-bbdb-badc1011d043 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-1 uve
2026-05-18 03:18:43,800 - DEBUG - Connected network default-domain:ctest-SecurityGroupBasicRegressionTests1-43022588:ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 of vm dd9c7899-29b5-46c5-bbdb-badc1011d043 is present in vrouter an-jenkins-deploy-platform-ansible-os-5792-1 uve
2026-05-18 03:18:43,800 - DEBUG - Verifying the dd9c7899-29b5-46c5-bbdb-badc1011d043 virtual network link through opserver 10.0.0.23
2026-05-18 03:18:43,801 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-05-18 03:18:43,816 - DEBUG - VM link and name as {'name': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat'}
2026-05-18 03:18:43,816 - DEBUG - Verifying the dd9c7899-29b5-46c5-bbdb-badc1011d043 virtual network link through opserver 10.0.0.22
2026-05-18 03:18:43,817 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines
2026-05-18 03:18:43,832 - DEBUG - VM link and name as {'name': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat'}
2026-05-18 03:18:43,832 - DEBUG - Verifying the dd9c7899-29b5-46c5-bbdb-badc1011d043 virtual network link through opserver 10.0.0.240
2026-05-18 03:18:43,832 - DEBUG - Requesting: http://10.0.0.240:8081/analytics/uves/virtual-machines
2026-05-18 03:18:43,845 - DEBUG - VM link and name as {'name': 'dd9c7899-29b5-46c5-bbdb-badc1011d043', 'href': 'http://10.0.0.240:8081/analytics/uves/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043?flat'}
2026-05-18 03:18:43,845 - INFO - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 validations in Opserver passed
2026-05-18 03:18:43,845 - INFO - Waiting for VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 to be up..
2026-05-18 03:18:43,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:43,845 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/dd9c7899-29b5-46c5-bbdb-badc1011d043
2026-05-18 03:18:43,854 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f6e1a577-e1d4-4d7a-89a8-7b2cf910bea1
2026-05-18 03:18:45,180 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.54 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=156 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.540/79.304/156.068/76.764 ms')
2026-05-18 03:18:45,180 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 passed
2026-05-18 03:18:45,255 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:45,255 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050, IP 25.227.25.68, Port 22
2026-05-18 03:18:45,323 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:45,408 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:18:50,408 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:50,409 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050, IP 25.227.25.68, Port 22
2026-05-18 03:18:50,474 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:50,567 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:18:55,568 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:18:55,568 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050, IP 25.227.25.68, Port 22
2026-05-18 03:18:55,634 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-05-18 03:18:55,727 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-18 03:19:00,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-18 03:19:00,728 - DEBUG - Waiting to SSH to VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050, IP 25.227.25.68, Port 22
2026-05-18 03:19:00,899 - DEBUG - VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050 is ready for SSH connections
2026-05-18 03:19:00,899 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:19:00,909 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:19:00,922 - DEBUG - Security group ctest-test_sec_group-14291580 is attached to the VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:00,947 - DEBUG - Agent: Security group ctest-test_sec_group-14291580 is attached to the VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,071 - INFO - Validated that SG ctest-test_sec_group-14291580 is bound to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,072 - INFO - Remove security group ctest-test_sec_group-14291580 from VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,364 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:19:01,373 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:19:01,392 - WARNING - Security group ctest-test_sec_group-14291580 is not attached to the VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,620 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/e5187b81-41a8-4789-8ff4-9fb1fc4eb1c3
2026-05-18 03:19:01,631 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/210cd262-1696-4720-9e92-93601a51722a
2026-05-18 03:19:01,649 - DEBUG - Security group ctest-test_sec_group-14291580 is attached to the VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,671 - DEBUG - Agent: Security group ctest-test_sec_group-14291580 is attached to the VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,768 - INFO - Validated that SG ctest-test_sec_group-14291580 is bound to VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:01,769 - INFO - Try deleting the security group ctest-test_sec_group-14291580 with back ref.
2026-05-18 03:19:01,769 - INFO - Deleting Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580']
2026-05-18 03:19:01,973 - INFO - Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580'] deleted successfully.
2026-05-18 03:19:01,974 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:19:01,981 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:19:02,089 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:19:02,194 - INFO - Security group ctest-test_sec_group-14291580 removed from the API Server
2026-05-18 03:19:02,194 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-05-18 03:19:02,202 - DEBUG - Requesting: http://10.0.0.23:8082/domain/4bbec0ba-f2cb-4fcb-b8f1-efc2a7bf0989
2026-05-18 03:19:02,214 - DEBUG - Requesting: http://10.0.0.23:8082/project/a0a39034-bc34-49e7-b5be-2b5e2790ca33
2026-05-18 03:19:02,315 - DEBUG - Requesting: http://10.0.0.23:8082/access-control-lists
2026-05-18 03:19:02,325 - DEBUG - ACLs for Security group ctest-test_sec_group-14291580 removed from the API Server
2026-05-18 03:19:02,332 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.23
2026-05-18 03:19:02,343 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.23
2026-05-18 03:19:02,350 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.22
2026-05-18 03:19:02,357 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.22
2026-05-18 03:19:02,362 - INFO - security group ctest-test_sec_group-14291580 removed from Control-node 10.0.0.240
2026-05-18 03:19:02,375 - DEBUG - Security group ctest-test_sec_group-14291580 ACLs removed from the control node 10.0.0.240
2026-05-18 03:19:02,375 - INFO - Validated that SG ctest-test_sec_group-14291580 is not in control nodes
2026-05-18 03:19:02,385 - ERROR - Security group deleted, when it is attached to a VM.
2026-05-18 03:19:02,386 - INFO - Removing the security group from VM ctest-SecurityGroupBasicRegressionTests1-43022588-23709050
2026-05-18 03:19:02,512 - INFO - Removing the security group from VM ctest-SecurityGroupBasicRegressionTests1-43022588-60675943
2026-05-18 03:19:02,640 - INFO - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322
2026-05-18 03:19:02,686 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-55b66d5c-2cb2-4d8f-a201-340f132e86e8']
2026-05-18 03:19:02,687 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:04,727 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cc3972f9-6db2-4456-a122-bf1d5ce912bc']
2026-05-18 03:19:04,727 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:06,766 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-940b9a1c-8218-41fc-b670-5da41cfcc807']
2026-05-18 03:19:06,766 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:08,808 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6c95f64b-bb0f-4d1e-9da4-525814eabf42']
2026-05-18 03:19:08,809 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:10,849 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8d4648aa-1e0e-4e8d-8866-378602efbf08']
2026-05-18 03:19:10,849 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:12,898 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0c4af7ef-fa80-4e87-b09a-5de08390fd8a']
2026-05-18 03:19:12,899 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:14,954 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c97ffe96-924b-4920-acf2-2ab87ffff916']
2026-05-18 03:19:14,954 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:17,006 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8960d321-6f56-4723-bcab-e35f0ece418d']
2026-05-18 03:19:17,006 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:19,046 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b85d9c46-dfe1-4607-95a0-3f01b7a76740']
2026-05-18 03:19:19,047 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:21,091 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8ee78718-d130-44cb-a263-7d7ed3f41d9c']
2026-05-18 03:19:21,091 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:23,137 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-894d0e56-66f2-41b4-a22c-dd5ab647bece']
2026-05-18 03:19:23,137 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:25,179 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3a3bbc0c-a5c6-488e-92a9-a9a55d5de9f3']
2026-05-18 03:19:25,180 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:27,226 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-903aca32-1eac-4eda-9694-31e3d2cc061b']
2026-05-18 03:19:27,226 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:29,342 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2cbce17a-1ae3-4ccf-8ade-a551f9993bdb']
2026-05-18 03:19:29,343 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:31,383 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a124e6db-7629-4d53-83d1-f7617b80057f']
2026-05-18 03:19:31,383 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:33,430 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ee438f3c-14be-404a-a377-b55a96591399']
2026-05-18 03:19:33,430 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:35,470 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-038dd870-76df-4cbd-a575-25968caf7f3b']
2026-05-18 03:19:35,471 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:37,513 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-17832e4c-773f-4087-97cd-dacbcc454846']
2026-05-18 03:19:37,513 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:39,559 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-47c05fff-b4f8-469f-bfab-e88cc1b5f1a6']
2026-05-18 03:19:39,559 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:41,604 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-78a82cf3-6c53-48d0-afff-8c2ee72e3a59']
2026-05-18 03:19:41,605 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:43,649 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b348ef45-7663-40ff-9546-57925f478f90']
2026-05-18 03:19:43,649 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:45,695 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0c90d6d1-766f-40e6-921f-df6859ad9863']
2026-05-18 03:19:45,695 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:47,736 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-94ff5006-15f8-465e-b8c4-b6c951d0672b']
2026-05-18 03:19:47,736 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:49,784 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3a08eec6-792c-4da7-83d0-211864030360']
2026-05-18 03:19:49,785 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:51,832 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f8543462-e590-4368-b4a6-16c3d1df769b']
2026-05-18 03:19:51,833 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:53,877 - DEBUG - VN 251a1b18-2105-4f52-96ff-128b23aa52bd still in use: Unable to complete operation on network 251a1b18-2105-4f52-96ff-128b23aa52bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-da9eb19f-f38d-46ed-8c68-5da4c3c8730b']
2026-05-18 03:19:53,878 - WARNING - Deleting VN ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 failed..Will retry
2026-05-18 03:19:53,878 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:19:53,888 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:19:58,889 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:19:58,901 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:03,902 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:03,912 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:08,913 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:08,924 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:13,924 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:13,933 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:18,934 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:18,944 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:23,945 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:23,954 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:28,956 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:28,967 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:33,968 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:33,980 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:38,980 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:38,996 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:43,998 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/79d56dfc-c39f-43ee-b43c-2a1d9bc2060a
2026-05-18 03:20:44,008 - WARNING - RI ctest-SecurityGroupBasicRegressionTests1-43022588-91849322 is still found in API-Server
2026-05-18 03:20:44,008 - INFO - Deleting Security group: ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-test_sec_group-14291580']
2026-05-18 03:20:45,307 - ERROR - AssertionError
Python 3.9.25: /usr/bin/python3
Mon May 18 03:19:02 2026
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/scripts/securitygroup/test_regression_basic.py in test_sec_group_basic(self=)
127 errmsg = "Security group deleted, when it is attached to a VM."
128 self.logger.error(errmsg)
129 assert False, errmsg
130
131 #Ping test, should fail
errmsg = 'Security group deleted, when it is attached to a VM.'
AssertionError: Security group deleted, when it is attached to a VM.
__cause__ = None
__class__ =
__context__ = NoIdError('Error: oper 1 url /fqname-to-id body ...\n \'ctest-test_sec_group-14291580\'] not found')
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Assertion failed.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ('Security group deleted, when it is attached to a VM.',)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
Traceback (most recent call last):
File "/contrail-test/scripts/securitygroup/test_regression_basic.py", line 122, in test_sec_group_basic
secgroup = self.vnc_lib.security_group_read(
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper
return func(self, *args, **kwargs)
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 700, in _object_read
(args_ok, result) = self._read_args_to_id(
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1114, in _read_args_to_id
return (True, self.fq_name_to_id(res_type, fq_name))
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper
return func(self, *args, **kwargs)
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1395, in fq_name_to_id
content = self._request_server(OP_POST, uri, data=json_body)
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1126, in _request_server
return self._request(
File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1180, in _request
raise NoIdError('Error: oper %s url %s body %s response %s'
vnc_api.exceptions.NoIdError: Unknown id: Error: oper 1 url /fqname-to-id body {"type": "security-group", "fq_name": ["default-domain", "ctest-SecurityGroupBasicRegressionTests1-43022588", "ctest-test_sec_group-14291580"]} response Name ['default-domain',
'ctest-SecurityGroupBasicRegressionTests1-43022588',
'ctest-test_sec_group-14291580'] not found
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
result = function(self, *args, **kwargs)
File "/contrail-test/scripts/securitygroup/test_regression_basic.py", line 129, in test_sec_group_basic
assert False, errmsg
AssertionError: Security group deleted, when it is attached to a VM.
2026-05-18 03:20:45,321 - DEBUG - No XMPP flaps were noticed during the test
2026-05-18 03:20:45,322 - INFO -
2026-05-18 03:20:45,322 - INFO - END TEST : test_sec_group_basic : FAILED[0:03:16]
2026-05-18 03:20:45,322 - INFO - --------------------------------------------------------------------------------
2026-05-18 03:20:45,512 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:47,656 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:49,804 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:51,944 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:54,088 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:56,236 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:20:58,380 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:00,580 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:02,744 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:04,892 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:07,048 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:09,204 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:11,360 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:13,500 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:15,640 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:17,788 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:19,936 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:22,076 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:24,224 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:26,360 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:28,548 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:30,700 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:32,856 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:35,000 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:37,144 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:39,285 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:41,428 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:43,568 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:45,720 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:47,860 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:50,008 - WARNING - Project ctest-SecurityGroupBasicRegressionTests1-43022588 still has VNs [{'to': ['default-domain', 'ctest-SecurityGroupBasicRegressionTests1-43022588', 'ctest-SecurityGroupBasicRegressionTests1-43022588-91849322'], 'uuid': '251a1b18-2105-4f52-96ff-128b23aa52bd', 'href': 'http://10.0.0.240:8082/virtual-network/251a1b18-2105-4f52-96ff-128b23aa52bd'}] before deletion
2026-05-18 03:21:50,008 - WARNING - One or more references still present, will not delete the project ctest-SecurityGroupBasicRegressionTests1-43022588