2026-05-18 16:52:38,173 - INFO - Domain Default found not creating
2026-05-18 16:52:38,342 - INFO - Project ctest-TestSvcRegr-79130829 not found, creating it
2026-05-18 16:52:38,799 - INFO - Created Project:ctest-TestSvcRegr-79130829, ID : 9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:40,310 - INFO - ================================================================================
2026-05-18 16:52:40,311 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-05-18 16:52:40,567 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.17': '0'}} with
2026-05-18 16:52:40,567 - INFO - Initial checks done. Running the testcase now
2026-05-18 16:52:40,567 - INFO -
2026-05-18 16:52:41,139 - DEBUG - Response for create_network : {'network': {'id': 'b393872a-7e00-4cbb-b06e-3762fe41d007', 'name': 'ctest-mgmt_vn-32989983', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'project_id': '9ad29f620c4c45b5bdf234805e790f24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79130829', 'ctest-mgmt_vn-32989983'], 'port_security_enabled': True, 'description': ''}}
2026-05-18 16:52:41,335 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c6db42b9-e833-40ed-aabc-b0cb6ce5bfa6', 'name': '', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'network_id': 'b393872a-7e00-4cbb-b06e-3762fe41d007', 'ip_version': 4, 'cidr': '92.218.120.64/26', 'allocation_pools': [{'start': '92.218.120.66', 'end': '92.218.120.126'}], 'gateway_ip': '92.218.120.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '92.218.120.66', 'tags': [], 'project_id': '9ad29f620c4c45b5bdf234805e790f24'}}
2026-05-18 16:52:41,357 - INFO - Created VN ctest-mgmt_vn-32989983
2026-05-18 16:52:41,411 - DEBUG - VN ctest-mgmt_vn-32989983 UUID is b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:41,526 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:41,835 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:41,875 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:41,889 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:41,970 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:41,984 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:41,995 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2cc44813-c892-449f-89dc-6e9d9842a340
2026-05-18 16:52:42,004 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2cc44813-c892-449f-89dc-6e9d9842a340
2026-05-18 16:52:42,011 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/8a2bacd8-d635-47a4-ba9f-afc22bbe78c5
2026-05-18 16:52:42,018 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-18 16:52:42,018 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:42,028 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2cc44813-c892-449f-89dc-6e9d9842a340
2026-05-18 16:52:42,042 - INFO - Verified VN network id 11 for VN b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:42,042 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32989983 passed
2026-05-18 16:52:42,043 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:42,052 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2cc44813-c892-449f-89dc-6e9d9842a340
2026-05-18 16:52:42,061 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/2cc44813-c892-449f-89dc-6e9d9842a340
2026-05-18 16:52:42,069 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/8a2bacd8-d635-47a4-ba9f-afc22bbe78c5
2026-05-18 16:52:42,082 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79130829:ctest-mgmt_vn-32989983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79130829', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12939834770937892027', 'uuid-lslong': '12713159696425537543'}, 'enable': 'true', 'created': '2026-05-18T16:52:41', 'last-modified': '2026-05-18T16:52:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.775985'}
2026-05-18 16:52:42,088 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-18 16:52:42,089 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32989983 passed
2026-05-18 16:52:42,089 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32989983 in API_Server ======
2026-05-18 16:52:42,089 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:42,096 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:42,108 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:42,180 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/b393872a-7e00-4cbb-b06e-3762fe41d007
2026-05-18 16:52:42,190 - DEBUG - =>VN ctest-mgmt_vn-32989983 has no policy to be verified
2026-05-18 16:52:42,190 - DEBUG - Verifying the vn in opserver
2026-05-18 16:52:42,190 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79130829:ctest-mgmt_vn-32989983 virtual network link through opserver 10.0.0.249
2026-05-18 16:52:42,191 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-18 16:52:42,488 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-18 16:52:42,548 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79130829:ctest-mgmt_vn-32989983', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79130829:ctest-mgmt_vn-32989983?flat'}
2026-05-18 16:52:42,548 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79130829:ctest-mgmt_vn-32989983 is found in opserver
2026-05-18 16:52:42,558 - DEBUG - Do not have enough data to verify VN in agent
2026-05-18 16:52:42,562 - DEBUG - VRF ids for VN ctest-mgmt_vn-32989983: {}
2026-05-18 16:52:42,734 - DEBUG - Response for create_network : {'network': {'id': 'd32904d2-28fe-41a9-914c-aaa7c01d6cfc', 'name': 'ctest-left_vn-84062076', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'project_id': '9ad29f620c4c45b5bdf234805e790f24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79130829', 'ctest-left_vn-84062076'], 'port_security_enabled': True, 'description': ''}}
2026-05-18 16:52:42,878 - DEBUG - Response for create_subnet : {'subnet': {'id': '44681182-ff62-4a18-9050-a6e4e6c9bafd', 'name': '', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'network_id': 'd32904d2-28fe-41a9-914c-aaa7c01d6cfc', 'ip_version': 4, 'cidr': '129.201.109.128/26', 'allocation_pools': [{'start': '129.201.109.130', 'end': '129.201.109.190'}], 'gateway_ip': '129.201.109.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.201.109.130', 'tags': [], 'project_id': '9ad29f620c4c45b5bdf234805e790f24'}}
2026-05-18 16:52:42,898 - INFO - Created VN ctest-left_vn-84062076
2026-05-18 16:52:42,951 - DEBUG - VN ctest-left_vn-84062076 UUID is d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,064 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:43,070 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:43,081 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:43,152 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,163 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,172 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ababb8db-b4ea-425b-8160-253bf2a71f5a
2026-05-18 16:52:43,180 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ababb8db-b4ea-425b-8160-253bf2a71f5a
2026-05-18 16:52:43,186 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9e118183-65dc-41a4-90d9-13bce8d18083
2026-05-18 16:52:43,195 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-18 16:52:43,195 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,206 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ababb8db-b4ea-425b-8160-253bf2a71f5a
2026-05-18 16:52:43,224 - INFO - Verified VN network id 12 for VN d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,224 - INFO - Verifications in API Server for VN ctest-left_vn-84062076 passed
2026-05-18 16:52:43,224 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,235 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ababb8db-b4ea-425b-8160-253bf2a71f5a
2026-05-18 16:52:43,243 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/ababb8db-b4ea-425b-8160-253bf2a71f5a
2026-05-18 16:52:43,253 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/9e118183-65dc-41a4-90d9-13bce8d18083
2026-05-18 16:52:43,267 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79130829:ctest-left_vn-84062076', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79130829', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15215698116725326249', 'uuid-lslong': '10469930871188974844'}, 'enable': 'true', 'created': '2026-05-18T16:52:42', 'last-modified': '2026-05-18T16:52:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417746'}
2026-05-18 16:52:43,273 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-18 16:52:43,273 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-84062076 passed
2026-05-18 16:52:43,273 - DEBUG - ====Verifying policy data for ctest-left_vn-84062076 in API_Server ======
2026-05-18 16:52:43,273 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:43,279 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:43,292 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:43,369 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/d32904d2-28fe-41a9-914c-aaa7c01d6cfc
2026-05-18 16:52:43,380 - DEBUG - =>VN ctest-left_vn-84062076 has no policy to be verified
2026-05-18 16:52:43,381 - DEBUG - Verifying the vn in opserver
2026-05-18 16:52:43,381 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79130829:ctest-left_vn-84062076 virtual network link through opserver 10.0.0.249
2026-05-18 16:52:43,381 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-18 16:52:43,390 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79130829:ctest-left_vn-84062076', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79130829:ctest-left_vn-84062076?flat'}
2026-05-18 16:52:43,391 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79130829:ctest-left_vn-84062076 is found in opserver
2026-05-18 16:52:43,400 - DEBUG - Do not have enough data to verify VN in agent
2026-05-18 16:52:43,404 - DEBUG - VRF ids for VN ctest-left_vn-84062076: {}
2026-05-18 16:52:43,593 - DEBUG - Response for create_network : {'network': {'id': '5d725bbd-7de8-4a4e-a2d3-b5e60dd21549', 'name': 'ctest-right_vn-55249586', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'project_id': '9ad29f620c4c45b5bdf234805e790f24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-79130829', 'ctest-right_vn-55249586'], 'port_security_enabled': True, 'description': ''}}
2026-05-18 16:52:43,782 - DEBUG - Response for create_subnet : {'subnet': {'id': '5f3632cc-3239-43f5-a20f-45302337184a', 'name': '', 'tenant_id': '9ad29f620c4c45b5bdf234805e790f24', 'network_id': '5d725bbd-7de8-4a4e-a2d3-b5e60dd21549', 'ip_version': 4, 'cidr': '17.41.214.128/26', 'allocation_pools': [{'start': '17.41.214.130', 'end': '17.41.214.190'}], 'gateway_ip': '17.41.214.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.41.214.130', 'tags': [], 'project_id': '9ad29f620c4c45b5bdf234805e790f24'}}
2026-05-18 16:52:43,802 - INFO - Created VN ctest-right_vn-55249586
2026-05-18 16:52:43,855 - DEBUG - VN ctest-right_vn-55249586 UUID is 5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:43,964 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:43,969 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:43,982 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:44,055 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,065 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,075 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/727921fa-4e5e-49f0-a5d5-cbcbcf7c67a1
2026-05-18 16:52:44,083 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/727921fa-4e5e-49f0-a5d5-cbcbcf7c67a1
2026-05-18 16:52:44,091 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/90bbd767-21af-4c30-a9ef-581c8e27c718
2026-05-18 16:52:44,098 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 16:52:44,098 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,107 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/727921fa-4e5e-49f0-a5d5-cbcbcf7c67a1
2026-05-18 16:52:44,121 - INFO - Verified VN network id 13 for VN 5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,121 - INFO - Verifications in API Server for VN ctest-right_vn-55249586 passed
2026-05-18 16:52:44,122 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,132 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/727921fa-4e5e-49f0-a5d5-cbcbcf7c67a1
2026-05-18 16:52:44,140 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/727921fa-4e5e-49f0-a5d5-cbcbcf7c67a1
2026-05-18 16:52:44,147 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/90bbd767-21af-4c30-a9ef-581c8e27c718
2026-05-18 16:52:44,160 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-79130829:ctest-right_vn-55249586', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-79130829', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6733545262291634766', 'uuid-lslong': '11732921453909251401'}, 'enable': 'true', 'created': '2026-05-18T16:52:43', 'last-modified': '2026-05-18T16:52:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406510'}
2026-05-18 16:52:44,167 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-18 16:52:44,167 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-55249586 passed
2026-05-18 16:52:44,167 - DEBUG - ====Verifying policy data for ctest-right_vn-55249586 in API_Server ======
2026-05-18 16:52:44,167 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2026-05-18 16:52:44,173 - DEBUG - Requesting: http://10.0.0.249:8082/domain/491448e1-1545-4d03-8de3-08bc26108cb1
2026-05-18 16:52:44,183 - DEBUG - Requesting: http://10.0.0.249:8082/project/9ad29f62-0c4c-45b5-bdf2-34805e790f24
2026-05-18 16:52:44,255 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/5d725bbd-7de8-4a4e-a2d3-b5e60dd21549
2026-05-18 16:52:44,264 - DEBUG - =>VN ctest-right_vn-55249586 has no policy to be verified
2026-05-18 16:52:44,264 - DEBUG - Verifying the vn in opserver
2026-05-18 16:52:44,264 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-79130829:ctest-right_vn-55249586 virtual network link through opserver 10.0.0.249
2026-05-18 16:52:44,264 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-18 16:52:44,273 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-79130829:ctest-right_vn-55249586', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-79130829:ctest-right_vn-55249586?flat'}
2026-05-18 16:52:44,273 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-79130829:ctest-right_vn-55249586 is found in opserver
2026-05-18 16:52:44,283 - DEBUG - Do not have enough data to verify VN in agent
2026-05-18 16:52:44,288 - DEBUG - VRF ids for VN ctest-right_vn-55249586: {}
2026-05-18 16:52:44,409 - DEBUG - Services list from nova: [