2026-07-06 15:09:56,530 - INFO - Domain Default found not creating
2026-07-06 15:09:56,666 - INFO - Project ctest-TestSvcRegr-78696000 not found, creating it
2026-07-06 15:09:57,119 - INFO - Created Project:ctest-TestSvcRegr-78696000, ID : 4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:09:58,564 - INFO - ================================================================================
2026-07-06 15:09:58,564 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-07-06 15:09:58,822 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.14': '0'}} with
2026-07-06 15:09:58,822 - INFO - Initial checks done. Running the testcase now
2026-07-06 15:09:58,822 - INFO -
2026-07-06 15:09:59,434 - DEBUG - Response for create_network : {'network': {'id': 'eaa545e7-d184-48b7-96df-47471ae9e7a7', 'name': 'ctest-mgmt_vn-77312631', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'project_id': '4c53045b470e4f679eec0cc9b27850ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-mgmt_vn-77312631'], 'port_security_enabled': True, 'description': ''}}
2026-07-06 15:09:59,611 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f4eab50-66e1-43c5-9aac-14b80e163e9b', 'name': '', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'network_id': 'eaa545e7-d184-48b7-96df-47471ae9e7a7', 'ip_version': 4, 'cidr': '179.204.217.192/26', 'allocation_pools': [{'start': '179.204.217.194', 'end': '179.204.217.254'}], 'gateway_ip': '179.204.217.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '179.204.217.194', 'tags': [], 'project_id': '4c53045b470e4f679eec0cc9b27850ee'}}
2026-07-06 15:09:59,627 - INFO - Created VN ctest-mgmt_vn-77312631
2026-07-06 15:09:59,677 - DEBUG - VN ctest-mgmt_vn-77312631 UUID is eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:09:59,775 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:00,094 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:00,120 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:00,134 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:00,210 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,221 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,230 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:10:00,238 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:10:00,247 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/dcbcf41b-683b-4efc-ba66-8a8b15c2d236
2026-07-06 15:10:00,255 - DEBUG - Route Targets: ['target:64512:8000004']
2026-07-06 15:10:00,255 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,263 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:10:00,276 - INFO - Verified VN network id 10 for VN eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,277 - INFO - Verifications in API Server for VN ctest-mgmt_vn-77312631 passed
2026-07-06 15:10:00,277 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,286 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:10:00,294 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:10:00,302 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/dcbcf41b-683b-4efc-ba66-8a8b15c2d236
2026-07-06 15:10:00,316 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78696000', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16907997237987264695', 'uuid-lslong': '10871486396215453607'}, 'enable': 'true', 'created': '2026-07-06T15:09:59', 'last-modified': '2026-07-06T15:09:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.733646'}
2026-07-06 15:10:00,323 - DEBUG - Route Targets: ['target:64512:8000004']
2026-07-06 15:10:00,323 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-77312631 passed
2026-07-06 15:10:00,323 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-77312631 in API_Server ======
2026-07-06 15:10:00,323 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:00,330 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:00,342 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:00,423 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/eaa545e7-d184-48b7-96df-47471ae9e7a7
2026-07-06 15:10:00,434 - DEBUG - =>VN ctest-mgmt_vn-77312631 has no policy to be verified
2026-07-06 15:10:00,435 - DEBUG - Verifying the vn in opserver
2026-07-06 15:10:00,435 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 virtual network link through opserver 10.0.0.249
2026-07-06 15:10:00,435 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-07-06 15:10:00,758 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-07-06 15:10:00,811 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631?flat'}
2026-07-06 15:10:00,812 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 is found in opserver
2026-07-06 15:10:00,821 - DEBUG - Do not have enough data to verify VN in agent
2026-07-06 15:10:00,826 - DEBUG - VRF ids for VN ctest-mgmt_vn-77312631: {}
2026-07-06 15:10:00,992 - DEBUG - Response for create_network : {'network': {'id': '1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f', 'name': 'ctest-left_vn-17292037', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'project_id': '4c53045b470e4f679eec0cc9b27850ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-left_vn-17292037'], 'port_security_enabled': True, 'description': ''}}
2026-07-06 15:10:01,199 - DEBUG - Response for create_subnet : {'subnet': {'id': '77c6e1d8-bce2-4890-9a91-a6aa3641af97', 'name': '', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'network_id': '1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f', 'ip_version': 4, 'cidr': '175.66.169.0/26', 'allocation_pools': [{'start': '175.66.169.2', 'end': '175.66.169.62'}], 'gateway_ip': '175.66.169.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '175.66.169.2', 'tags': [], 'project_id': '4c53045b470e4f679eec0cc9b27850ee'}}
2026-07-06 15:10:01,215 - INFO - Created VN ctest-left_vn-17292037
2026-07-06 15:10:01,269 - DEBUG - VN ctest-left_vn-17292037 UUID is 1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,372 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:01,377 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:01,387 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:01,461 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,471 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,479 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:10:01,486 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:10:01,492 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/179ca9fa-9ac9-4b71-abe5-8397374aa021
2026-07-06 15:10:01,499 - DEBUG - Route Targets: ['target:64512:8000005']
2026-07-06 15:10:01,499 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,509 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:10:01,525 - INFO - Verified VN network id 11 for VN 1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,525 - INFO - Verifications in API Server for VN ctest-left_vn-17292037 passed
2026-07-06 15:10:01,525 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,535 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:10:01,542 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:10:01,550 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/179ca9fa-9ac9-4b71-abe5-8397374aa021
2026-07-06 15:10:01,565 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78696000', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2203383010244578625', 'uuid-lslong': '13825582613926751311'}, 'enable': 'true', 'created': '2026-07-06T15:10:00', 'last-modified': '2026-07-06T15:10:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396670'}
2026-07-06 15:10:01,571 - DEBUG - Route Targets: ['target:64512:8000005']
2026-07-06 15:10:01,571 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-17292037 passed
2026-07-06 15:10:01,572 - DEBUG - ====Verifying policy data for ctest-left_vn-17292037 in API_Server ======
2026-07-06 15:10:01,572 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:01,578 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:01,588 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:01,658 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/1e93fd2c-7dc2-4d41-bfde-5622e3ebac4f
2026-07-06 15:10:01,666 - DEBUG - =>VN ctest-left_vn-17292037 has no policy to be verified
2026-07-06 15:10:01,666 - DEBUG - Verifying the vn in opserver
2026-07-06 15:10:01,666 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037 virtual network link through opserver 10.0.0.249
2026-07-06 15:10:01,666 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-07-06 15:10:01,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037?flat'}
2026-07-06 15:10:01,675 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037 is found in opserver
2026-07-06 15:10:01,685 - DEBUG - Do not have enough data to verify VN in agent
2026-07-06 15:10:01,690 - DEBUG - VRF ids for VN ctest-left_vn-17292037: {}
2026-07-06 15:10:01,878 - DEBUG - Response for create_network : {'network': {'id': 'b07d6491-252a-433d-830f-3a1c6d95e728', 'name': 'ctest-right_vn-20278133', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'project_id': '4c53045b470e4f679eec0cc9b27850ee', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-right_vn-20278133'], 'port_security_enabled': True, 'description': ''}}
2026-07-06 15:10:02,026 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4a2a489-0010-448c-9250-e776684c43c6', 'name': '', 'tenant_id': '4c53045b470e4f679eec0cc9b27850ee', 'network_id': 'b07d6491-252a-433d-830f-3a1c6d95e728', 'ip_version': 4, 'cidr': '153.201.113.128/26', 'allocation_pools': [{'start': '153.201.113.130', 'end': '153.201.113.190'}], 'gateway_ip': '153.201.113.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '153.201.113.130', 'tags': [], 'project_id': '4c53045b470e4f679eec0cc9b27850ee'}}
2026-07-06 15:10:02,042 - INFO - Created VN ctest-right_vn-20278133
2026-07-06 15:10:02,093 - DEBUG - VN ctest-right_vn-20278133 UUID is b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,194 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:02,199 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:02,210 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:02,282 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,292 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,302 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:10:02,310 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:10:02,317 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/36f0f491-e821-44ca-9678-ff36944b6b36
2026-07-06 15:10:02,324 - DEBUG - Route Targets: ['target:64512:8000006']
2026-07-06 15:10:02,324 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,332 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:10:02,346 - INFO - Verified VN network id 12 for VN b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,346 - INFO - Verifications in API Server for VN ctest-right_vn-20278133 passed
2026-07-06 15:10:02,346 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,355 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:10:02,362 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:10:02,369 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/36f0f491-e821-44ca-9678-ff36944b6b36
2026-07-06 15:10:02,385 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-78696000', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12717431497320710973', 'uuid-lslong': '9443830837391255336'}, 'enable': 'true', 'created': '2026-07-06T15:10:01', 'last-modified': '2026-07-06T15:10:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391566'}
2026-07-06 15:10:02,392 - DEBUG - Route Targets: ['target:64512:8000006']
2026-07-06 15:10:02,392 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-20278133 passed
2026-07-06 15:10:02,392 - DEBUG - ====Verifying policy data for ctest-right_vn-20278133 in API_Server ======
2026-07-06 15:10:02,392 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:10:02,398 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:10:02,410 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:10:02,486 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b07d6491-252a-433d-830f-3a1c6d95e728
2026-07-06 15:10:02,494 - DEBUG - =>VN ctest-right_vn-20278133 has no policy to be verified
2026-07-06 15:10:02,494 - DEBUG - Verifying the vn in opserver
2026-07-06 15:10:02,494 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133 virtual network link through opserver 10.0.0.249
2026-07-06 15:10:02,494 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-07-06 15:10:02,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133?flat'}
2026-07-06 15:10:02,504 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133 is found in opserver
2026-07-06 15:10:02,515 - DEBUG - Do not have enough data to verify VN in agent
2026-07-06 15:10:02,520 - DEBUG - VRF ids for VN ctest-right_vn-20278133: {}
2026-07-06 15:10:02,585 - DEBUG - Services list from nova: [, , ]
2026-07-06 15:10:02,918 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6085-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6085-1)
2026-07-06 15:10:03,311 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6085-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6085-1)
2026-07-06 15:10:03,311 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-63529304']
2026-07-06 15:10:03,673 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-63529304'] created succesfully
2026-07-06 15:10:03,894 - DEBUG - Image by name cirros_in_net not found
2026-07-06 15:10:03,894 - DEBUG - Installing image cirros_in_net
2026-07-06 15:10:03,894 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'}
2026-07-06 15:10:03,895 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-07-06 15:10:03,895 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-07-06 15:10:03,910 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz
2026-07-06 15:10:04,085 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz
2026-07-06 15:10:04,085 - DEBUG - Unzip image
2026-07-06 15:10:04,085 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz
2026-07-06 15:10:04,229 - DEBUG - Try to create image in user context
2026-07-06 15:10:04,471 - DEBUG - Image has been created in user context
2026-07-06 15:10:04,471 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk
2026-07-06 15:10:04,488 - DEBUG - Downloaded image has been removed
2026-07-06 15:10:04,501 - DEBUG - Uploaded image status = active
2026-07-06 15:10:04,914 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6085-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6085-1)
2026-07-06 15:10:04,914 - INFO - Waiting for VM ctest-pt_svm0-81240421 to be up..
2026-07-06 15:10:04,953 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-07-06 15:10:10,065 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-07-06 15:10:15,149 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-07-06 15:10:20,247 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-07-06 15:10:25,339 - DEBUG - VM is in ACTIVE state now
2026-07-06 15:10:25,340 - INFO - VM name : ctest-pt_svm0-81240421
2026-07-06 15:10:25,417 - DEBUG - VM ctest-pt_svm0-81240421 ID is 5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:10:25,435 - DEBUG - VM ctest-pt_svm0-81240421 launched on Node cn-jenkins-deploy-platform-ansible-os-6085-1
2026-07-06 15:10:25,664 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:10:25,672 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/d362b893-fc9f-4e7b-a008-d71fa9fb56e9
2026-07-06 15:10:25,680 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0b4838f-035f-43ab-a911-a8f48b506278
2026-07-06 15:10:25,690 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0f2dae9-830d-414a-990c-9dcaab6aba14
2026-07-06 15:10:28,984 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-07-06 15:10:28,984 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-81240421 failed!
2026-07-06 15:10:29,038 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 is 179.204.217.193 and allocation pool is NOT set
2026-07-06 15:10:33,104 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2026-07-06 15:10:33,104 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-81240421 failed!
2026-07-06 15:10:33,157 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 is 179.204.217.193 and allocation pool is NOT set
2026-07-06 15:10:35,224 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=5.21 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.780 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.780/2.992/5.205/2.212 ms')
2026-07-06 15:10:35,225 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-81240421 passed
2026-07-06 15:10:35,381 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:35,381 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:35,448 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-07-06 15:10:35,543 - DEBUG - VM ctest-pt_svm0-81240421 is NOT ready for SSH connections, VM status: ACTIVE
2026-07-06 15:10:40,544 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:40,544 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:40,611 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-07-06 15:10:40,691 - DEBUG - VM ctest-pt_svm0-81240421 is NOT ready for SSH connections, VM status: ACTIVE
2026-07-06 15:10:45,691 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:45,692 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:45,751 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-07-06 15:10:45,841 - DEBUG - VM ctest-pt_svm0-81240421 is NOT ready for SSH connections, VM status: ACTIVE
2026-07-06 15:10:50,842 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:50,842 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:50,908 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-07-06 15:10:50,994 - DEBUG - VM ctest-pt_svm0-81240421 is NOT ready for SSH connections, VM status: ACTIVE
2026-07-06 15:10:55,995 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:55,995 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:56,183 - DEBUG - VM ctest-pt_svm0-81240421 is ready for SSH connections
2026-07-06 15:10:56,337 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:56,337 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:56,511 - DEBUG - VM ctest-pt_svm0-81240421 is ready for SSH connections
2026-07-06 15:10:56,661 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:56,661 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81240421, IP 179.204.217.195, Port 22
2026-07-06 15:10:56,824 - DEBUG - VM ctest-pt_svm0-81240421 is ready for SSH connections
2026-07-06 15:10:56,833 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-si-90596537']
2026-07-06 15:10:57,729 - INFO - Waiting for VM ctest-left_vm-21218261 to be up..
2026-07-06 15:10:57,808 - DEBUG - VM is in ACTIVE state now
2026-07-06 15:10:57,808 - INFO - VM name : ctest-left_vm-21218261
2026-07-06 15:10:57,886 - DEBUG - VM ctest-left_vm-21218261 ID is 69d1e7d8-ce67-4f07-ae4f-9c4d4995a1a4
2026-07-06 15:10:57,886 - DEBUG - VM ctest-left_vm-21218261 launched on Node cn-jenkins-deploy-platform-ansible-os-6085-1
2026-07-06 15:10:57,971 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/69d1e7d8-ce67-4f07-ae4f-9c4d4995a1a4
2026-07-06 15:10:57,980 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/556142c3-1f2a-43ec-ae3a-1b007cd527f7
2026-07-06 15:10:59,233 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.484 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.484/1.707/2.930/1.223 ms')
2026-07-06 15:10:59,233 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-21218261 passed
2026-07-06 15:10:59,385 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:10:59,385 - DEBUG - Waiting to SSH to VM ctest-left_vm-21218261, IP 175.66.169.3, Port 22
2026-07-06 15:10:59,556 - DEBUG - VM ctest-left_vm-21218261 is ready for SSH connections
2026-07-06 15:10:59,556 - INFO - Waiting for VM ctest-right_vm-05035156 to be up..
2026-07-06 15:10:59,622 - DEBUG - VM is in ACTIVE state now
2026-07-06 15:10:59,622 - INFO - VM name : ctest-right_vm-05035156
2026-07-06 15:10:59,694 - DEBUG - VM ctest-right_vm-05035156 ID is 978addde-7531-47a8-843b-b5c67d06e75c
2026-07-06 15:10:59,694 - DEBUG - VM ctest-right_vm-05035156 launched on Node cn-jenkins-deploy-platform-ansible-os-6085-1
2026-07-06 15:10:59,760 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/978addde-7531-47a8-843b-b5c67d06e75c
2026-07-06 15:10:59,767 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/dd794f1b-6152-4e92-bcb1-2df865cf156b
2026-07-06 15:11:01,008 - 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.97 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.396 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.396/1.681/2.967/1.285 ms')
2026-07-06 15:11:01,008 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-05035156 passed
2026-07-06 15:11:01,157 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:01,157 - DEBUG - Waiting to SSH to VM ctest-right_vm-05035156, IP 153.201.113.131, Port 22
2026-07-06 15:11:01,331 - DEBUG - VM ctest-right_vm-05035156 is ready for SSH connections
2026-07-06 15:11:01,336 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = 65535], action_list = simple_action = pass, gateway_name = None, apply_service = ['default-domain:ctest-TestSvcRegr-78696000:ctest-si-90596537'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-07-06 15:11:01,753 - INFO - Attaching policy ctest-policy-27655841 to vn ctest-left_vn-17292037
2026-07-06 15:11:02,285 - INFO - Attaching policy ctest-policy-27655841 to vn ctest-right_vn-20278133
2026-07-06 15:11:02,713 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-63529304'] created succesfully
2026-07-06 15:11:02,933 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-si-90596537'] created succesfully
2026-07-06 15:11:02,933 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:02,939 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:02,949 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:03,021 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/5b7cecc5-6f9e-4fe5-8589-202f83446f64
2026-07-06 15:11:03,032 - DEBUG - SI 'ctest-si-90596537' has service template ref to ctest-service_template_1-63529304
2026-07-06 15:11:03,032 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:03,037 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:03,047 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:03,111 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/5b7cecc5-6f9e-4fe5-8589-202f83446f64
2026-07-06 15:11:03,120 - DEBUG - Requesting: http://10.0.0.249:8082/port-tuple/ba497d67-685c-467e-84b0-687c23493460
2026-07-06 15:11:03,130 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/d362b893-fc9f-4e7b-a008-d71fa9fb56e9
2026-07-06 15:11:03,139 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0b4838f-035f-43ab-a911-a8f48b506278
2026-07-06 15:11:03,148 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0f2dae9-830d-414a-990c-9dcaab6aba14
2026-07-06 15:11:03,334 - DEBUG - VM is in ACTIVE state now
2026-07-06 15:11:03,725 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:11:03,733 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/d362b893-fc9f-4e7b-a008-d71fa9fb56e9
2026-07-06 15:11:03,743 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0b4838f-035f-43ab-a911-a8f48b506278
2026-07-06 15:11:03,751 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0f2dae9-830d-414a-990c-9dcaab6aba14
2026-07-06 15:11:03,760 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/02d62632-5bef-458b-9bb0-79b9aa0f65b0
2026-07-06 15:11:03,768 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/0a6bbf3b-2395-48c1-ab87-a4dd0c62d6f4
2026-07-06 15:11:03,774 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/ff7e0dd9-77f3-4413-89cf-09b4240a8d8a
2026-07-06 15:11:03,782 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/97cee155-5383-47df-a2ac-d78eecc2389f
2026-07-06 15:11:03,788 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/9938679f-9a20-4a83-a64c-f155927a90ae
2026-07-06 15:11:03,794 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/9febc427-7262-4390-be59-6edc2463fd55
2026-07-06 15:11:03,802 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/8ffefa03-be79-408f-a57e-8b8f9b562f81
2026-07-06 15:11:03,808 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/b643adeb-b890-426c-be7a-3373cef5b842
2026-07-06 15:11:03,813 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/b8be0bab-2d9f-40db-845c-a08a25f8ee0d
2026-07-06 15:11:04,215 - DEBUG - VM is in ACTIVE state now
2026-07-06 15:11:04,215 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:11:04,222 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/d362b893-fc9f-4e7b-a008-d71fa9fb56e9
2026-07-06 15:11:04,231 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0b4838f-035f-43ab-a911-a8f48b506278
2026-07-06 15:11:04,240 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f0f2dae9-830d-414a-990c-9dcaab6aba14
2026-07-06 15:11:05,472 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.14 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.638 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.638/0.887/1.137/0.249 ms')
2026-07-06 15:11:05,472 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-81240421 passed
2026-07-06 15:11:05,505 - DEBUG - VM ctest-pt_svm0-81240421 Tap interface: {'index': '6', 'name': 'tapf0f2dae9-83', 'uuid': 'f0f2dae9-830d-414a-990c-9dcaab6aba14', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037:ctest-left_vn-17292037', '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': '44', 'l2_label': '48', 'vxlan_id': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '175.66.169.4', 'mac_addr': '02:f0:f2:da:e9:83', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:f0f2dae9-830d-414a-990c-9dcaab6aba14', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': 'd362b893-fc9f-4e7b-a008-d71fa9fb56e9', '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-07-06 15:11:05,505 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037:ctest-left_vn-17292037
2026-07-06 15:11:05,509 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037:ctest-left_vn-17292037', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.14:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-07-06 15:11:05,677 - DEBUG - VM ctest-pt_svm0-81240421 : Tap interface tapf0f2dae9-83 is set to Active
2026-07-06 15:11:05,677 - DEBUG - Tap interface tapf0f2dae9-83 detail : {'index': '6', 'name': 'tapf0f2dae9-83', 'uuid': 'f0f2dae9-830d-414a-990c-9dcaab6aba14', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037:ctest-left_vn-17292037', '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': '44', 'l2_label': '48', 'vxlan_id': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '175.66.169.4', 'mac_addr': '02:f0:f2:da:e9:83', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:f0f2dae9-830d-414a-990c-9dcaab6aba14', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': 'd362b893-fc9f-4e7b-a008-d71fa9fb56e9', '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-07-06 15:11:05,870 - DEBUG - Starting Layer 2 verification in Agent
2026-07-06 15:11:05,881 - DEBUG - Layer 2 path is seen for VM MAC 02:f0:f2:da:e9:83 in agent 10.0.0.249
2026-07-06 15:11:05,882 - DEBUG - Active layer 2 route in agent is present for VMI tapf0f2dae9-83
2026-07-06 15:11:05,882 - DEBUG - L2 label(48) matches bw route table and interface table
2026-07-06 15:11:05,887 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037 is found in Agent of node 10.0.0.249
2026-07-06 15:11:06,092 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037 is consistent in agent 10.0.0.249
2026-07-06 15:11:06,092 - DEBUG - Route for VM IP ['175.66.169.4'] is consistent in agent 10.0.0.249
2026-07-06 15:11:06,092 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037 verification for VM ctest-pt_svm0-81240421 in Agent 10.0.0.249 passed
2026-07-06 15:11:06,281 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249
2026-07-06 15:11:06,292 - DEBUG - Route for VM MAC 02:f0:f2:da:e9:83 is consistent in agent 10.0.0.249
2026-07-06 15:11:06,310 - DEBUG - VM ctest-pt_svm0-81240421 Tap interface: {'index': '5', 'name': 'tapf0b4838f-03', 'uuid': 'f0b4838f-035f-43ab-a911-a8f48b506278', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631:ctest-mgmt_vn-77312631', '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': '39', 'l2_label': '43', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '179.204.217.195', 'mac_addr': '02:f0:b4:83:8f:03', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:f0b4838f-035f-43ab-a911-a8f48b506278', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '43', '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-07-06 15:11:06,310 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631:ctest-mgmt_vn-77312631
2026-07-06 15:11:06,314 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631:ctest-mgmt_vn-77312631', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.14:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-07-06 15:11:06,514 - DEBUG - VM ctest-pt_svm0-81240421 : Tap interface tapf0b4838f-03 is set to Active
2026-07-06 15:11:06,515 - DEBUG - Tap interface tapf0b4838f-03 detail : {'index': '5', 'name': 'tapf0b4838f-03', 'uuid': 'f0b4838f-035f-43ab-a911-a8f48b506278', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631:ctest-mgmt_vn-77312631', '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': '39', 'l2_label': '43', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '179.204.217.195', 'mac_addr': '02:f0:b4:83:8f:03', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:f0b4838f-035f-43ab-a911-a8f48b506278', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '43', '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-07-06 15:11:06,713 - DEBUG - Starting Layer 2 verification in Agent
2026-07-06 15:11:06,722 - DEBUG - Layer 2 path is seen for VM MAC 02:f0:b4:83:8f:03 in agent 10.0.0.249
2026-07-06 15:11:06,723 - DEBUG - Active layer 2 route in agent is present for VMI tapf0b4838f-03
2026-07-06 15:11:06,723 - DEBUG - L2 label(43) matches bw route table and interface table
2026-07-06 15:11:06,728 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 is found in Agent of node 10.0.0.249
2026-07-06 15:11:06,939 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 is consistent in agent 10.0.0.249
2026-07-06 15:11:06,939 - DEBUG - Route for VM IP ['179.204.217.195'] is consistent in agent 10.0.0.249
2026-07-06 15:11:06,939 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-mgmt_vn-77312631 verification for VM ctest-pt_svm0-81240421 in Agent 10.0.0.249 passed
2026-07-06 15:11:07,129 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249
2026-07-06 15:11:07,139 - DEBUG - Route for VM MAC 02:f0:b4:83:8f:03 is consistent in agent 10.0.0.249
2026-07-06 15:11:07,155 - DEBUG - VM ctest-pt_svm0-81240421 Tap interface: {'index': '7', 'name': 'tapd362b893-fc', 'uuid': 'd362b893-fc9f-4e7b-a008-d71fa9fb56e9', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133:ctest-right_vn-20278133', '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': '49', 'l2_label': '53', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '153.201.113.132', 'mac_addr': '02:d3:62:b8:93:fc', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:d362b893-fc9f-4e7b-a008-d71fa9fb56e9', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '29', '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': 'f0f2dae9-830d-414a-990c-9dcaab6aba14', '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-07-06 15:11:07,155 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133:ctest-right_vn-20278133
2026-07-06 15:11:07,160 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133:ctest-right_vn-20278133', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.14:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-07-06 15:11:07,363 - DEBUG - VM ctest-pt_svm0-81240421 : Tap interface tapd362b893-fc is set to Active
2026-07-06 15:11:07,364 - DEBUG - Tap interface tapd362b893-fc detail : {'index': '7', 'name': 'tapd362b893-fc', 'uuid': 'd362b893-fc9f-4e7b-a008-d71fa9fb56e9', 'vrf_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133:ctest-right_vn-20278133', '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': '49', 'l2_label': '53', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133', 'vm_uuid': '5723146a-e0f3-4bd1-87cb-b9701ca53aef', 'vm_name': 'ctest-pt_svm0-81240421', 'ip_addr': '153.201.113.132', 'mac_addr': '02:d3:62:b8:93:fc', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-78696000:d362b893-fc9f-4e7b-a008-d71fa9fb56e9', 'sg_uuid_list': ['cc8a4a2e-d464-41d0-a0eb-01507499765e'], 'static_route_list': None, 'vm_project_uuid': '4c53045b-470e-4f67-9eec-0cc9b27850ee', 'admin_state': 'Enabled', 'flow_key_idx': '29', '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': 'f0f2dae9-830d-414a-990c-9dcaab6aba14', '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-07-06 15:11:07,553 - DEBUG - Starting Layer 2 verification in Agent
2026-07-06 15:11:07,565 - DEBUG - Layer 2 path is seen for VM MAC 02:d3:62:b8:93:fc in agent 10.0.0.249
2026-07-06 15:11:07,565 - DEBUG - Active layer 2 route in agent is present for VMI tapd362b893-fc
2026-07-06 15:11:07,565 - DEBUG - L2 label(53) matches bw route table and interface table
2026-07-06 15:11:07,570 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133 is found in Agent of node 10.0.0.249
2026-07-06 15:11:07,782 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133 is consistent in agent 10.0.0.249
2026-07-06 15:11:07,782 - DEBUG - Route for VM IP ['153.201.113.132'] is consistent in agent 10.0.0.249
2026-07-06 15:11:07,782 - DEBUG - VN default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133 verification for VM ctest-pt_svm0-81240421 in Agent 10.0.0.249 passed
2026-07-06 15:11:07,977 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249
2026-07-06 15:11:07,988 - DEBUG - Route for VM MAC 02:d3:62:b8:93:fc is consistent in agent 10.0.0.249
2026-07-06 15:11:07,988 - INFO - VM ctest-pt_svm0-81240421 verifications in Compute nodes passed
2026-07-06 15:11:07,988 - DEBUG - The SVMs in the SI are : []
2026-07-06 15:11:07,988 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:11:07,995 - DEBUG - Service VM for SI 'ctest-si-90596537' is launched
2026-07-06 15:11:07,995 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:08,000 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:08,010 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:08,079 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/5b7cecc5-6f9e-4fe5-8589-202f83446f64
2026-07-06 15:11:08,087 - DEBUG - SI ctest-si-90596537 has Port Tuple: ['ctest-port_tuple-72760720']
2026-07-06 15:11:08,189 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-78696000:ctest-left_vn-17292037
2026-07-06 15:11:08,333 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-78696000:ctest-right_vn-20278133
2026-07-06 15:11:08,495 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,504 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,504 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,513 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,513 - INFO - Route to 153.201.113.131 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-6085-1, and SI ctest-si-90596537 is seen as the NH
2026-07-06 15:11:08,668 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,676 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,676 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,682 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,682 - INFO - Route to 175.66.169.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-6085-1, and SI ctest-si-90596537 is seen as the NH
2026-07-06 15:11:08,682 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,682 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-07-06 15:11:08,682 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 153.201.113.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2026-07-06 15:11:08,682 - DEBUG - ping -s 56 -c 3 -W 1 153.201.113.131
2026-07-06 15:11:12,675 - DEBUG - PING 153.201.113.131 (153.201.113.131): 56 data bytes
64 bytes from 153.201.113.131: seq=0 ttl=61 time=5.630 ms
64 bytes from 153.201.113.131: seq=1 ttl=61 time=2.259 ms
64 bytes from 153.201.113.131: seq=2 ttl=61 time=4.219 ms
--- 153.201.113.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 2.259/4.036/5.630 ms
2026-07-06 15:11:12,675 - INFO - Ping to IP 153.201.113.131 from VM ctest-left_vm-21218261 passed
2026-07-06 15:11:12,675 - INFO - Dettaching policy ctest-policy-27655841 from vn ctest-right_vn-20278133
2026-07-06 15:11:12,873 - INFO - Dettaching policy ctest-policy-27655841 from vn ctest-left_vn-17292037
2026-07-06 15:11:13,164 - INFO - Deleted policy ctest-policy-27655841
2026-07-06 15:11:13,164 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-si-90596537']
2026-07-06 15:11:13,259 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-78696000', 'ctest-si-90596537']
2026-07-06 15:11:13,259 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:13,264 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:13,280 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:13,356 - DEBUG - Service instance ctest-si-90596537 removed from api server
2026-07-06 15:11:13,356 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5723146a-e0f3-4bd1-87cb-b9701ca53aef
2026-07-06 15:11:13,365 - DEBUG - All Service VMs unlinked from SI ctest-si-90596537
2026-07-06 15:11:13,369 - DEBUG - 0 svc intances found in all projects. They are []
2026-07-06 15:11:13,370 - DEBUG - 0 svc intances found in current project. They are []
2026-07-06 15:11:13,421 - DEBUG - 0 svc intances found in all projects. They are []
2026-07-06 15:11:13,421 - DEBUG - 0 svc intances found in current project. They are []
2026-07-06 15:11:13,421 - INFO - Deleting VM ctest-pt_svm0-81240421
2026-07-06 15:11:13,504 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-63529304']
2026-07-06 15:11:13,581 - INFO - Service template: ['default-domain', 'ctest-service_template_1-63529304'] deleted successfully.
2026-07-06 15:11:13,582 - INFO - Deleting VM ctest-right_vm-05035156
2026-07-06 15:11:13,657 - INFO - Deleting VM ctest-left_vm-21218261
2026-07-06 15:11:13,728 - INFO - Deleting VN ctest-right_vn-20278133
2026-07-06 15:11:13,769 - DEBUG - VN b07d6491-252a-433d-830f-3a1c6d95e728 still in use: Unable to complete operation on network b07d6491-252a-433d-830f-3a1c6d95e728. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dd00db6f-ae8a-46fa-904b-8412826da090']
2026-07-06 15:11:13,769 - WARNING - Deleting VN ctest-right_vn-20278133 failed..Will retry
2026-07-06 15:11:15,813 - DEBUG - VN b07d6491-252a-433d-830f-3a1c6d95e728 still in use: Unable to complete operation on network b07d6491-252a-433d-830f-3a1c6d95e728. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2ab30e25-b971-4a3e-be84-bf0539045953']
2026-07-06 15:11:15,813 - WARNING - Deleting VN ctest-right_vn-20278133 failed..Will retry
2026-07-06 15:11:17,916 - DEBUG - Response for deleting network ()
2026-07-06 15:11:17,916 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c4574b81-4112-488b-9347-18abf8471c48
2026-07-06 15:11:17,921 - DEBUG - Response Code: 404
2026-07-06 15:11:17,922 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:17,927 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:17,979 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:18,053 - INFO - Validated that VN ctest-right_vn-20278133 is not found in API Server
2026-07-06 15:11:18,063 - DEBUG - VN ctest-right_vn-20278133 is not present in Agent 10.0.0.249
2026-07-06 15:11:18,063 - INFO - Validated that VN ctest-right_vn-20278133 is not in any agent
2026-07-06 15:11:18,078 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-20278133 info
2026-07-06 15:11:18,079 - INFO - Deleting VN ctest-left_vn-17292037
2026-07-06 15:11:18,174 - DEBUG - Response for deleting network ()
2026-07-06 15:11:18,174 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/9c784ae8-4a7d-4ca1-9fe1-7ebefeace371
2026-07-06 15:11:18,180 - DEBUG - Response Code: 404
2026-07-06 15:11:18,180 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:18,184 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:18,238 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:18,311 - INFO - Validated that VN ctest-left_vn-17292037 is not found in API Server
2026-07-06 15:11:18,321 - DEBUG - VN ctest-left_vn-17292037 is not present in Agent 10.0.0.249
2026-07-06 15:11:18,321 - INFO - Validated that VN ctest-left_vn-17292037 is not in any agent
2026-07-06 15:11:18,334 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-17292037 info
2026-07-06 15:11:18,334 - INFO - Deleting VN ctest-mgmt_vn-77312631
2026-07-06 15:11:18,444 - DEBUG - Response for deleting network ()
2026-07-06 15:11:18,444 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2a5396d3-f3a8-4cb6-af69-1b90c53c4fa4
2026-07-06 15:11:18,455 - DEBUG - Response Code: 404
2026-07-06 15:11:18,455 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-07-06 15:11:18,460 - DEBUG - Requesting: http://10.0.0.249:8082/domain/0264fbcd-f957-4e90-9c9d-245a715bbdc1
2026-07-06 15:11:18,509 - DEBUG - Requesting: http://10.0.0.249:8082/project/4c53045b-470e-4f67-9eec-0cc9b27850ee
2026-07-06 15:11:18,581 - INFO - Validated that VN ctest-mgmt_vn-77312631 is not found in API Server
2026-07-06 15:11:18,590 - DEBUG - VN ctest-mgmt_vn-77312631 is not present in Agent 10.0.0.249
2026-07-06 15:11:18,590 - INFO - Validated that VN ctest-mgmt_vn-77312631 is not in any agent
2026-07-06 15:11:18,602 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-77312631 info
2026-07-06 15:11:18,870 - DEBUG - No XMPP flaps were noticed during the test
2026-07-06 15:11:18,870 - INFO -
2026-07-06 15:11:18,870 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:20]
2026-07-06 15:11:18,870 - INFO - --------------------------------------------------------------------------------
2026-07-06 15:11:19,607 - INFO - Deleted project: ctest-TestSvcRegr-78696000, ID : 4c53045b-470e-4f67-9eec-0cc9b27850ee