]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1)
2026-01-19 03:07:58,398 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-67773050']
2026-01-19 03:07:58,714 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-67773050'] created succesfully
2026-01-19 03:07:59,142 - DEBUG - Image by name tiny_nat_fw not found
2026-01-19 03:07:59,142 - DEBUG - Installing image tiny_nat_fw
2026-01-19 03:07:59,142 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-01-19 03:07:59,143 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-19 03:07:59,143 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-01-19 03:07:59,160 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:07:59,218 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:07:59,219 - DEBUG - Unzip image
2026-01-19 03:07:59,219 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:07:59,242 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-19 03:07:59,243 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-67773050']
2026-01-19 03:07:59,427 - INFO - Service template: ['default-domain', 'ctest-service_template_1-67773050'] deleted successfully.
2026-01-19 03:07:59,427 - INFO - Skip interface_detach for VM ctest-right_vm-17470216 in state BUILD
2026-01-19 03:07:59,427 - INFO - Deleting VM ctest-right_vm-17470216
2026-01-19 03:07:59,454 - ERROR - VM ctest-right_vm-17470216 has failed to come up
2026-01-19 03:07:59,455 - ERROR - Nova failed to get host of the VM
2026-01-19 03:07:59,455 - ERROR - VM ctest-right_vm-17470216 has failed to come up
2026-01-19 03:07:59,455 - ERROR - Nova failed to get host of the VM
2026-01-19 03:07:59,548 - INFO - Skip interface_detach for VM ctest-left_vm-05464829 in state BUILD
2026-01-19 03:07:59,549 - INFO - Deleting VM ctest-left_vm-05464829
2026-01-19 03:07:59,549 - ERROR - VM ctest-left_vm-05464829 has failed to come up
2026-01-19 03:07:59,549 - ERROR - Nova failed to get host of the VM
2026-01-19 03:07:59,549 - ERROR - VM ctest-left_vm-05464829 has failed to come up
2026-01-19 03:07:59,549 - ERROR - Nova failed to get host of the VM
2026-01-19 03:07:59,629 - INFO - Deleting VN ctest-right_vn-30110740
2026-01-19 03:07:59,676 - DEBUG - VN 9e5f2509-41c5-4232-8044-bc8e27afebc2 still in use: Unable to complete operation on network 9e5f2509-41c5-4232-8044-bc8e27afebc2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b718f422-1ad7-441a-8b3d-4571a4d2b55c']
2026-01-19 03:07:59,676 - WARNING - Deleting VN ctest-right_vn-30110740 failed..Will retry
2026-01-19 03:08:01,918 - DEBUG - Response for deleting network ()
2026-01-19 03:08:01,918 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9dd01fcd-0765-4e81-b7b7-46790e3166e4
2026-01-19 03:08:01,935 - DEBUG - Response Code: 404
2026-01-19 03:08:01,935 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:01,941 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:01,957 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:02,057 - INFO - Validated that VN ctest-right_vn-30110740 is not found in API Server
2026-01-19 03:08:02,067 - DEBUG - VN ctest-right_vn-30110740 is not present in Agent 10.0.0.249
2026-01-19 03:08:02,076 - DEBUG - VN ctest-right_vn-30110740 is not present in Agent 10.0.0.65
2026-01-19 03:08:02,076 - INFO - Validated that VN ctest-right_vn-30110740 is not in any agent
2026-01-19 03:08:02,111 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-30110740 info
2026-01-19 03:08:02,111 - INFO - Deleting VN ctest-left_vn-64730050
2026-01-19 03:08:02,271 - DEBUG - Response for deleting network ()
2026-01-19 03:08:02,271 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/2a2a9ea0-64a0-4d66-93bf-f4ad4c646f73
2026-01-19 03:08:02,277 - DEBUG - Response Code: 404
2026-01-19 03:08:02,277 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:02,282 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:02,294 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:02,406 - INFO - Validated that VN ctest-left_vn-64730050 is not found in API Server
2026-01-19 03:08:02,419 - DEBUG - VN ctest-left_vn-64730050 is not present in Agent 10.0.0.249
2026-01-19 03:08:02,429 - DEBUG - VN ctest-left_vn-64730050 is not present in Agent 10.0.0.65
2026-01-19 03:08:02,429 - INFO - Validated that VN ctest-left_vn-64730050 is not in any agent
2026-01-19 03:08:02,464 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-64730050 info
2026-01-19 03:08:02,464 - INFO - Deleting VN ctest-mgmt_vn-46330512
2026-01-19 03:08:02,629 - DEBUG - Response for deleting network ()
2026-01-19 03:08:02,629 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5504660d-74e8-49a1-b808-66b0c428f037
2026-01-19 03:08:02,639 - DEBUG - Response Code: 404
2026-01-19 03:08:02,639 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:02,646 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:02,665 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:02,755 - INFO - Validated that VN ctest-mgmt_vn-46330512 is not found in API Server
2026-01-19 03:08:02,770 - DEBUG - VN ctest-mgmt_vn-46330512 is not present in Agent 10.0.0.249
2026-01-19 03:08:02,780 - DEBUG - VN ctest-mgmt_vn-46330512 is not present in Agent 10.0.0.65
2026-01-19 03:08:02,780 - INFO - Validated that VN ctest-mgmt_vn-46330512 is not in any agent
2026-01-19 03:08:02,818 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-46330512 info
2026-01-19 03:08:02,818 - INFO - Deleting VM ctest-vm-in-third-vn-57718165
2026-01-19 03:08:02,919 - INFO - Deleting VN ctest-third-vn-13182040
2026-01-19 03:08:02,966 - DEBUG - VN d025bbbd-40d8-4613-9f43-c23fb4e05e61 still in use: Unable to complete operation on network d025bbbd-40d8-4613-9f43-c23fb4e05e61. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f68a5ff9-4ecf-49a5-91e5-0f989cc7d44b']
2026-01-19 03:08:02,966 - WARNING - Deleting VN ctest-third-vn-13182040 failed..Will retry
2026-01-19 03:08:05,126 - DEBUG - Response for deleting network ()
2026-01-19 03:08:06,419 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:08:06,419 - INFO -
2026-01-19 03:08:06,419 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:30]
2026-01-19 03:08:06,419 - INFO - --------------------------------------------------------------------------------
2026-01-19 03:08:06,424 - INFO - ================================================================================
2026-01-19 03:08:06,424 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-01-19 03:08:07,713 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2026-01-19 03:08:07,713 - INFO - Initial checks done. Running the testcase now
2026-01-19 03:08:07,713 - INFO -
2026-01-19 03:08:07,999 - DEBUG - Response for create_network : {'network': {'id': '131ab0f8-5e56-4dd0-8b55-2851a7a67993', 'name': 'ctest-mgmt_vn-38865179', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'project_id': '85171377b57b466c98d70e7065d24e01', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72658348', 'ctest-mgmt_vn-38865179'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:08,240 - DEBUG - Response for create_subnet : {'subnet': {'id': '29f9ff08-8fb6-401e-9d76-2f84fcb1728a', 'name': '', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'network_id': '131ab0f8-5e56-4dd0-8b55-2851a7a67993', 'ip_version': 4, 'cidr': '126.220.161.64/26', 'allocation_pools': [{'start': '126.220.161.66', 'end': '126.220.161.126'}], 'gateway_ip': '126.220.161.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.220.161.66', 'tags': [], 'project_id': '85171377b57b466c98d70e7065d24e01'}}
2026-01-19 03:08:08,271 - INFO - Created VN ctest-mgmt_vn-38865179
2026-01-19 03:08:08,283 - DEBUG - VN ctest-mgmt_vn-38865179 UUID is 131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,357 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:08,362 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:08,373 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:08,481 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,497 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,508 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:08,516 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:08,524 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/8b258e71-7625-4b9f-90b0-d3d6d11454a9
2026-01-19 03:08:08,530 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:08,530 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,538 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:08,554 - INFO - Verified VN network id 16 for VN 131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,554 - INFO - Verifications in API Server for VN ctest-mgmt_vn-38865179 passed
2026-01-19 03:08:08,554 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,565 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:08,573 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:08,582 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/8b258e71-7625-4b9f-90b0-d3d6d11454a9
2026-01-19 03:08:08,594 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376607216896200144', 'uuid-lslong': '10039975275462556051'}, 'enable': 'true', 'created': '2026-01-19T03:08:07', 'last-modified': '2026-01-19T03:08:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419734'}
2026-01-19 03:08:08,603 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:08,609 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376607216896200144', 'uuid-lslong': '10039975275462556051'}, 'enable': 'true', 'created': '2026-01-19T03:08:07', 'last-modified': '2026-01-19T03:08:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429021'}
2026-01-19 03:08:08,617 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:08,624 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376607216896200144', 'uuid-lslong': '10039975275462556051'}, 'enable': 'true', 'created': '2026-01-19T03:08:07', 'last-modified': '2026-01-19T03:08:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441338'}
2026-01-19 03:08:08,631 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:08,631 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-38865179 passed
2026-01-19 03:08:08,631 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-38865179 in API_Server ======
2026-01-19 03:08:08,631 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:08,638 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:08,649 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:08,749 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/131ab0f8-5e56-4dd0-8b55-2851a7a67993
2026-01-19 03:08:08,759 - DEBUG - =>VN ctest-mgmt_vn-38865179 has no policy to be verified
2026-01-19 03:08:08,759 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:08,759 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:08,759 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:08,773 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179?flat'}
2026-01-19 03:08:08,773 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:08,773 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:08,788 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179?flat'}
2026-01-19 03:08:08,788 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:08,788 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:08,802 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179?flat'}
2026-01-19 03:08:08,802 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72658348:ctest-mgmt_vn-38865179 is found in opserver
2026-01-19 03:08:08,822 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:08,831 - DEBUG - VRF ids for VN ctest-mgmt_vn-38865179: {}
2026-01-19 03:08:09,102 - DEBUG - Response for create_network : {'network': {'id': '345a6b2c-08d0-4755-82a2-8e175304aa8f', 'name': 'ctest-left_vn-33905717', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'project_id': '85171377b57b466c98d70e7065d24e01', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72658348', 'ctest-left_vn-33905717'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:09,309 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ee1f8b2a-bc6a-4a46-bc2a-442863a7224c', 'name': '', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'network_id': '345a6b2c-08d0-4755-82a2-8e175304aa8f', 'ip_version': 4, 'cidr': '22.226.146.0/26', 'allocation_pools': [{'start': '22.226.146.2', 'end': '22.226.146.62'}], 'gateway_ip': '22.226.146.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22.226.146.2', 'tags': [], 'project_id': '85171377b57b466c98d70e7065d24e01'}}
2026-01-19 03:08:09,330 - INFO - Created VN ctest-left_vn-33905717
2026-01-19 03:08:09,343 - DEBUG - VN ctest-left_vn-33905717 UUID is 345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,412 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:09,421 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:09,432 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:09,527 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,538 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,549 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:09,556 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:09,563 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/6287c08a-f6b4-4e74-b2e4-db220e2d2c22
2026-01-19 03:08:09,571 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:09,571 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,581 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:09,597 - INFO - Verified VN network id 17 for VN 345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,597 - INFO - Verifications in API Server for VN ctest-left_vn-33905717 passed
2026-01-19 03:08:09,597 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,607 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:09,614 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:09,622 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/6287c08a-f6b4-4e74-b2e4-db220e2d2c22
2026-01-19 03:08:09,635 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3772445474746812245', 'uuid-lslong': '9413242401985964687'}, 'enable': 'true', 'created': '2026-01-19T03:08:09', 'last-modified': '2026-01-19T03:08:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.368908'}
2026-01-19 03:08:09,642 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:09,647 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3772445474746812245', 'uuid-lslong': '9413242401985964687'}, 'enable': 'true', 'created': '2026-01-19T03:08:09', 'last-modified': '2026-01-19T03:08:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384539'}
2026-01-19 03:08:09,653 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:09,658 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3772445474746812245', 'uuid-lslong': '9413242401985964687'}, 'enable': 'true', 'created': '2026-01-19T03:08:09', 'last-modified': '2026-01-19T03:08:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398067'}
2026-01-19 03:08:09,668 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:09,668 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-33905717 passed
2026-01-19 03:08:09,669 - DEBUG - ====Verifying policy data for ctest-left_vn-33905717 in API_Server ======
2026-01-19 03:08:09,669 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:09,675 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:09,687 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:09,777 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/345a6b2c-08d0-4755-82a2-8e175304aa8f
2026-01-19 03:08:09,790 - DEBUG - =>VN ctest-left_vn-33905717 has no policy to be verified
2026-01-19 03:08:09,790 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:09,790 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:09,790 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:09,803 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717?flat'}
2026-01-19 03:08:09,803 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:09,803 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:09,816 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717?flat'}
2026-01-19 03:08:09,816 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:09,816 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:09,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717?flat'}
2026-01-19 03:08:09,829 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72658348:ctest-left_vn-33905717 is found in opserver
2026-01-19 03:08:09,855 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:09,867 - DEBUG - VRF ids for VN ctest-left_vn-33905717: {}
2026-01-19 03:08:10,146 - DEBUG - Response for create_network : {'network': {'id': '91a3912d-7732-40f7-8b53-a09d05614f25', 'name': 'ctest-right_vn-25638667', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'project_id': '85171377b57b466c98d70e7065d24e01', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72658348', 'ctest-right_vn-25638667'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:10,395 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c3e963b-1487-41a0-ba93-8642ccbe3dfc', 'name': '', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'network_id': '91a3912d-7732-40f7-8b53-a09d05614f25', 'ip_version': 4, 'cidr': '69.165.9.192/26', 'allocation_pools': [{'start': '69.165.9.194', 'end': '69.165.9.254'}], 'gateway_ip': '69.165.9.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '69.165.9.194', 'tags': [], 'project_id': '85171377b57b466c98d70e7065d24e01'}}
2026-01-19 03:08:10,417 - INFO - Created VN ctest-right_vn-25638667
2026-01-19 03:08:10,429 - DEBUG - VN ctest-right_vn-25638667 UUID is 91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,496 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:10,502 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:10,513 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:10,608 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,619 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,630 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:10,638 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:10,645 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/155c01f5-7d13-4d29-a9e7-9527dc95eee2
2026-01-19 03:08:10,652 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:10,652 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,661 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:10,677 - INFO - Verified VN network id 18 for VN 91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,677 - INFO - Verifications in API Server for VN ctest-right_vn-25638667 passed
2026-01-19 03:08:10,677 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,687 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:10,695 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:10,703 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/155c01f5-7d13-4d29-a9e7-9527dc95eee2
2026-01-19 03:08:10,715 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10494391181162725623', 'uuid-lslong': '10039544590599540517'}, 'enable': 'true', 'created': '2026-01-19T03:08:10', 'last-modified': '2026-01-19T03:08:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367761'}
2026-01-19 03:08:10,723 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:10,732 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10494391181162725623', 'uuid-lslong': '10039544590599540517'}, 'enable': 'true', 'created': '2026-01-19T03:08:10', 'last-modified': '2026-01-19T03:08:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.382563'}
2026-01-19 03:08:10,738 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:10,745 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10494391181162725623', 'uuid-lslong': '10039544590599540517'}, 'enable': 'true', 'created': '2026-01-19T03:08:10', 'last-modified': '2026-01-19T03:08:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393104'}
2026-01-19 03:08:10,752 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:10,752 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-25638667 passed
2026-01-19 03:08:10,752 - DEBUG - ====Verifying policy data for ctest-right_vn-25638667 in API_Server ======
2026-01-19 03:08:10,752 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:10,760 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:10,770 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:10,872 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/91a3912d-7732-40f7-8b53-a09d05614f25
2026-01-19 03:08:10,884 - DEBUG - =>VN ctest-right_vn-25638667 has no policy to be verified
2026-01-19 03:08:10,884 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:10,885 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:10,885 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:10,898 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667?flat'}
2026-01-19 03:08:10,898 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:10,898 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:10,912 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667?flat'}
2026-01-19 03:08:10,912 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:10,912 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:10,925 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667?flat'}
2026-01-19 03:08:10,925 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72658348:ctest-right_vn-25638667 is found in opserver
2026-01-19 03:08:10,946 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:10,955 - DEBUG - VRF ids for VN ctest-right_vn-25638667: {}
2026-01-19 03:08:11,215 - DEBUG - Response for create_network : {'network': {'id': '9a588928-b9dd-4d6c-ab54-7bf95a331e3e', 'name': 'ctest-trans_left_vn-71608723', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'project_id': '85171377b57b466c98d70e7065d24e01', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72658348', 'ctest-trans_left_vn-71608723'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:11,416 - DEBUG - Response for create_subnet : {'subnet': {'id': '0df41841-fc99-4ef7-8c16-6bb4e565fae1', 'name': '', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'network_id': '9a588928-b9dd-4d6c-ab54-7bf95a331e3e', 'ip_version': 4, 'cidr': '133.120.12.192/26', 'allocation_pools': [{'start': '133.120.12.194', 'end': '133.120.12.254'}], 'gateway_ip': '133.120.12.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '133.120.12.194', 'tags': [], 'project_id': '85171377b57b466c98d70e7065d24e01'}}
2026-01-19 03:08:11,443 - INFO - Created VN ctest-trans_left_vn-71608723
2026-01-19 03:08:11,455 - DEBUG - VN ctest-trans_left_vn-71608723 UUID is 9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,530 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:11,537 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:11,553 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:11,645 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,657 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,667 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:11,680 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:11,689 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/f1a29830-07a8-45df-b607-38a46a29bb00
2026-01-19 03:08:11,698 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-19 03:08:11,698 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,709 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:11,727 - INFO - Verified VN network id 19 for VN 9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,727 - INFO - Verifications in API Server for VN ctest-trans_left_vn-71608723 passed
2026-01-19 03:08:11,727 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,742 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:11,751 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:11,759 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/f1a29830-07a8-45df-b607-38a46a29bb00
2026-01-19 03:08:11,772 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11121790087801425260', 'uuid-lslong': '12345628789419744830'}, 'enable': 'true', 'created': '2026-01-19T03:08:11', 'last-modified': '2026-01-19T03:08:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416542'}
2026-01-19 03:08:11,782 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-19 03:08:11,788 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11121790087801425260', 'uuid-lslong': '12345628789419744830'}, 'enable': 'true', 'created': '2026-01-19T03:08:11', 'last-modified': '2026-01-19T03:08:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.431910'}
2026-01-19 03:08:11,793 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-19 03:08:11,798 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11121790087801425260', 'uuid-lslong': '12345628789419744830'}, 'enable': 'true', 'created': '2026-01-19T03:08:11', 'last-modified': '2026-01-19T03:08:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437404'}
2026-01-19 03:08:11,806 - DEBUG - Route Targets: ['target:64512:8000008']
2026-01-19 03:08:11,806 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-71608723 passed
2026-01-19 03:08:11,807 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-71608723 in API_Server ======
2026-01-19 03:08:11,807 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:11,815 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:11,828 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:11,927 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/9a588928-b9dd-4d6c-ab54-7bf95a331e3e
2026-01-19 03:08:11,941 - DEBUG - =>VN ctest-trans_left_vn-71608723 has no policy to be verified
2026-01-19 03:08:11,941 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:11,941 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:11,941 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:11,953 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723?flat'}
2026-01-19 03:08:11,953 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:11,953 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:11,966 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723?flat'}
2026-01-19 03:08:11,966 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:11,966 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:11,980 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723?flat'}
2026-01-19 03:08:11,980 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72658348:ctest-trans_left_vn-71608723 is found in opserver
2026-01-19 03:08:12,002 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:12,012 - DEBUG - VRF ids for VN ctest-trans_left_vn-71608723: {}
2026-01-19 03:08:12,281 - DEBUG - Response for create_network : {'network': {'id': 'a6c05084-cb03-4635-91f6-eb0db717d21c', 'name': 'ctest-trans_right_vn-06942823', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'project_id': '85171377b57b466c98d70e7065d24e01', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-72658348', 'ctest-trans_right_vn-06942823'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:12,514 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d8a3f6d-b917-42b2-9bfc-cf882e5c0350', 'name': '', 'tenant_id': '85171377b57b466c98d70e7065d24e01', 'network_id': 'a6c05084-cb03-4635-91f6-eb0db717d21c', 'ip_version': 4, 'cidr': '186.210.128.64/26', 'allocation_pools': [{'start': '186.210.128.66', 'end': '186.210.128.126'}], 'gateway_ip': '186.210.128.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '186.210.128.66', 'tags': [], 'project_id': '85171377b57b466c98d70e7065d24e01'}}
2026-01-19 03:08:12,536 - INFO - Created VN ctest-trans_right_vn-06942823
2026-01-19 03:08:12,549 - DEBUG - VN ctest-trans_right_vn-06942823 UUID is a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,621 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:12,628 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:12,640 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:12,726 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,736 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,744 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:12,751 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:12,758 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2b90ef97-1437-4fb5-af75-e2b39092f27a
2026-01-19 03:08:12,764 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-19 03:08:12,764 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,773 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:12,789 - INFO - Verified VN network id 20 for VN a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,789 - INFO - Verifications in API Server for VN ctest-trans_right_vn-06942823 passed
2026-01-19 03:08:12,789 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:12,801 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:12,808 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:12,815 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/2b90ef97-1437-4fb5-af75-e2b39092f27a
2026-01-19 03:08:12,827 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12015692337096377909', 'uuid-lslong': '10517852423909265948'}, 'enable': 'true', 'created': '2026-01-19T03:08:12', 'last-modified': '2026-01-19T03:08:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.359164'}
2026-01-19 03:08:12,832 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-19 03:08:12,838 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12015692337096377909', 'uuid-lslong': '10517852423909265948'}, 'enable': 'true', 'created': '2026-01-19T03:08:12', 'last-modified': '2026-01-19T03:08:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367146'}
2026-01-19 03:08:12,844 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-19 03:08:12,851 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-72658348', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12015692337096377909', 'uuid-lslong': '10517852423909265948'}, 'enable': 'true', 'created': '2026-01-19T03:08:12', 'last-modified': '2026-01-19T03:08:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380734'}
2026-01-19 03:08:12,857 - DEBUG - Route Targets: ['target:64512:8000009']
2026-01-19 03:08:12,857 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-06942823 passed
2026-01-19 03:08:12,857 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-06942823 in API_Server ======
2026-01-19 03:08:12,857 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:12,863 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:12,875 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:12,990 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/a6c05084-cb03-4635-91f6-eb0db717d21c
2026-01-19 03:08:13,004 - DEBUG - =>VN ctest-trans_right_vn-06942823 has no policy to be verified
2026-01-19 03:08:13,004 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:13,004 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:13,004 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:13,019 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823?flat'}
2026-01-19 03:08:13,019 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:13,019 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:13,033 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823?flat'}
2026-01-19 03:08:13,033 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:13,033 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:13,048 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823?flat'}
2026-01-19 03:08:13,048 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-72658348:ctest-trans_right_vn-06942823 is found in opserver
2026-01-19 03:08:13,073 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:13,087 - DEBUG - VRF ids for VN ctest-trans_right_vn-06942823: {}
2026-01-19 03:08:14,143 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1)
2026-01-19 03:08:15,404 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-2)
2026-01-19 03:08:15,404 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-38429037']
2026-01-19 03:08:15,701 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-38429037'] created succesfully
2026-01-19 03:08:16,033 - DEBUG - Image by name tiny_trans_fw not found
2026-01-19 03:08:16,034 - DEBUG - Installing image tiny_trans_fw
2026-01-19 03:08:16,034 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2026-01-19 03:08:16,034 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-19 03:08:16,034 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-01-19 03:08:16,067 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-19 03:08:16,107 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-19 03:08:16,108 - DEBUG - Unzip image
2026-01-19 03:08:16,108 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-01-19 03:08:16,127 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-01-19 03:08:16,127 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-38429037']
2026-01-19 03:08:16,235 - INFO - Service template: ['default-domain', 'ctest-service_template_1-38429037'] deleted successfully.
2026-01-19 03:08:16,235 - INFO - Skip interface_detach for VM ctest-right_vm-73693477 in state BUILD
2026-01-19 03:08:16,235 - INFO - Deleting VM ctest-right_vm-73693477
2026-01-19 03:08:16,235 - ERROR - VM ctest-right_vm-73693477 has failed to come up
2026-01-19 03:08:16,235 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:16,235 - ERROR - VM ctest-right_vm-73693477 has failed to come up
2026-01-19 03:08:16,235 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:16,332 - INFO - Skip interface_detach for VM ctest-left_vm-66555724 in state BUILD
2026-01-19 03:08:16,332 - INFO - Deleting VM ctest-left_vm-66555724
2026-01-19 03:08:16,332 - ERROR - VM ctest-left_vm-66555724 has failed to come up
2026-01-19 03:08:16,332 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:16,332 - ERROR - VM ctest-left_vm-66555724 has failed to come up
2026-01-19 03:08:16,332 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:16,396 - INFO - Deleting VN ctest-trans_right_vn-06942823
2026-01-19 03:08:16,545 - DEBUG - Response for deleting network ()
2026-01-19 03:08:16,545 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ed01e261-d38b-41e2-92a3-2ae0387fd452
2026-01-19 03:08:16,553 - DEBUG - Response Code: 404
2026-01-19 03:08:16,553 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:16,559 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:16,583 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:16,686 - INFO - Validated that VN ctest-trans_right_vn-06942823 is not found in API Server
2026-01-19 03:08:16,700 - DEBUG - VN ctest-trans_right_vn-06942823 is not present in Agent 10.0.0.249
2026-01-19 03:08:16,725 - DEBUG - VN ctest-trans_right_vn-06942823 is not present in Agent 10.0.0.65
2026-01-19 03:08:16,725 - INFO - Validated that VN ctest-trans_right_vn-06942823 is not in any agent
2026-01-19 03:08:16,762 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-06942823 info
2026-01-19 03:08:16,763 - INFO - Deleting VN ctest-trans_left_vn-71608723
2026-01-19 03:08:16,973 - DEBUG - Response for deleting network ()
2026-01-19 03:08:16,973 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/13060e8c-3ccb-4922-ae24-a23271d1beb7
2026-01-19 03:08:16,979 - DEBUG - Response Code: 404
2026-01-19 03:08:16,980 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:16,991 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:17,001 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:17,098 - INFO - Validated that VN ctest-trans_left_vn-71608723 is not found in API Server
2026-01-19 03:08:17,111 - DEBUG - VN ctest-trans_left_vn-71608723 is not present in Agent 10.0.0.249
2026-01-19 03:08:17,123 - DEBUG - VN ctest-trans_left_vn-71608723 is not present in Agent 10.0.0.65
2026-01-19 03:08:17,123 - INFO - Validated that VN ctest-trans_left_vn-71608723 is not in any agent
2026-01-19 03:08:17,163 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-71608723 info
2026-01-19 03:08:17,164 - INFO - Deleting VN ctest-right_vn-25638667
2026-01-19 03:08:17,214 - DEBUG - VN 91a3912d-7732-40f7-8b53-a09d05614f25 still in use: Unable to complete operation on network 91a3912d-7732-40f7-8b53-a09d05614f25. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-16026dbf-c19d-4f84-b21d-b6fb144be480']
2026-01-19 03:08:17,214 - WARNING - Deleting VN ctest-right_vn-25638667 failed..Will retry
2026-01-19 03:08:19,384 - DEBUG - Response for deleting network ()
2026-01-19 03:08:19,384 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f0e586da-f80d-4d8d-b048-e7e2a8c2fd44
2026-01-19 03:08:19,393 - DEBUG - Response Code: 404
2026-01-19 03:08:19,393 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:19,399 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:19,456 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:19,547 - INFO - Validated that VN ctest-right_vn-25638667 is not found in API Server
2026-01-19 03:08:19,557 - DEBUG - VN ctest-right_vn-25638667 is not present in Agent 10.0.0.249
2026-01-19 03:08:19,568 - DEBUG - VN ctest-right_vn-25638667 is not present in Agent 10.0.0.65
2026-01-19 03:08:19,568 - INFO - Validated that VN ctest-right_vn-25638667 is not in any agent
2026-01-19 03:08:19,598 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-25638667 info
2026-01-19 03:08:19,598 - INFO - Deleting VN ctest-left_vn-33905717
2026-01-19 03:08:19,749 - DEBUG - Response for deleting network ()
2026-01-19 03:08:19,749 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/074e21e3-4af8-40b3-a72a-47809e6c413c
2026-01-19 03:08:19,755 - DEBUG - Response Code: 404
2026-01-19 03:08:19,755 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:19,761 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:19,782 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:19,865 - INFO - Validated that VN ctest-left_vn-33905717 is not found in API Server
2026-01-19 03:08:19,876 - DEBUG - VN ctest-left_vn-33905717 is not present in Agent 10.0.0.249
2026-01-19 03:08:19,886 - DEBUG - VN ctest-left_vn-33905717 is not present in Agent 10.0.0.65
2026-01-19 03:08:19,886 - INFO - Validated that VN ctest-left_vn-33905717 is not in any agent
2026-01-19 03:08:19,922 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-33905717 info
2026-01-19 03:08:19,922 - INFO - Deleting VN ctest-mgmt_vn-38865179
2026-01-19 03:08:20,060 - DEBUG - Response for deleting network ()
2026-01-19 03:08:20,061 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/63ee155c-0f00-42d2-9c40-619d312c7c8d
2026-01-19 03:08:20,066 - DEBUG - Response Code: 404
2026-01-19 03:08:20,066 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:20,071 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:20,087 - DEBUG - Requesting: http://10.0.0.27:8082/project/85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:20,175 - INFO - Validated that VN ctest-mgmt_vn-38865179 is not found in API Server
2026-01-19 03:08:20,186 - DEBUG - VN ctest-mgmt_vn-38865179 is not present in Agent 10.0.0.249
2026-01-19 03:08:20,196 - DEBUG - VN ctest-mgmt_vn-38865179 is not present in Agent 10.0.0.65
2026-01-19 03:08:20,196 - INFO - Validated that VN ctest-mgmt_vn-38865179 is not in any agent
2026-01-19 03:08:20,228 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-38865179 info
2026-01-19 03:08:21,526 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:08:21,526 - INFO -
2026-01-19 03:08:21,526 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2026-01-19 03:08:21,526 - INFO - --------------------------------------------------------------------------------
2026-01-19 03:08:22,253 - INFO - Deleted project: ctest-TestSvcRegr-72658348, ID : 85171377-b57b-466c-98d7-0e7065d24e01
2026-01-19 03:08:22,441 - INFO - Domain Default found not creating
2026-01-19 03:08:22,612 - INFO - Project ctest-TestSvcRegr-56748827 not found, creating it
2026-01-19 03:08:23,239 - INFO - Created Project:ctest-TestSvcRegr-56748827, ID : b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:24,714 - INFO - ================================================================================
2026-01-19 03:08:24,714 - INFO - STARTING TEST : test_svc_CEM_22032
2026-01-19 03:08:26,024 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2026-01-19 03:08:26,024 - INFO - Initial checks done. Running the testcase now
2026-01-19 03:08:26,024 - INFO -
2026-01-19 03:08:26,672 - DEBUG - Response for create_network : {'network': {'id': 'f4c5f944-cb6a-4029-bc7a-6644a34420bd', 'name': 'ctest-mgmt_vn-79097218', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'ctest-mgmt_vn-79097218'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:27,006 - DEBUG - Response for create_subnet : {'subnet': {'id': '813261c8-a81e-40d9-b695-7eeff30151d0', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': 'f4c5f944-cb6a-4029-bc7a-6644a34420bd', 'ip_version': 4, 'cidr': '213.92.102.64/26', 'allocation_pools': [{'start': '213.92.102.66', 'end': '213.92.102.126'}], 'gateway_ip': '213.92.102.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '213.92.102.66', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:27,029 - INFO - Created VN ctest-mgmt_vn-79097218
2026-01-19 03:08:27,041 - DEBUG - VN ctest-mgmt_vn-79097218 UUID is f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,121 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:27,427 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:27,461 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:27,478 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:27,580 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,596 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,605 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:27,612 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:27,620 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7671db88-9729-4524-bb7a-0e73c8f96a19
2026-01-19 03:08:27,626 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:27,626 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,636 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:27,652 - INFO - Verified VN network id 16 for VN f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,652 - INFO - Verifications in API Server for VN ctest-mgmt_vn-79097218 passed
2026-01-19 03:08:27,652 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,662 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:27,670 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:27,679 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/7671db88-9729-4524-bb7a-0e73c8f96a19
2026-01-19 03:08:27,691 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17637777589532246057', 'uuid-lslong': '13581280071272112317'}, 'enable': 'true', 'created': '2026-01-19T03:08:26', 'last-modified': '2026-01-19T03:08:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.746794'}
2026-01-19 03:08:27,696 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:27,701 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17637777589532246057', 'uuid-lslong': '13581280071272112317'}, 'enable': 'true', 'created': '2026-01-19T03:08:26', 'last-modified': '2026-01-19T03:08:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.763429'}
2026-01-19 03:08:27,707 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:27,713 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17637777589532246057', 'uuid-lslong': '13581280071272112317'}, 'enable': 'true', 'created': '2026-01-19T03:08:26', 'last-modified': '2026-01-19T03:08:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.763888'}
2026-01-19 03:08:27,719 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:27,719 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-79097218 passed
2026-01-19 03:08:27,719 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-79097218 in API_Server ======
2026-01-19 03:08:27,719 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:27,729 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:27,743 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:27,836 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/f4c5f944-cb6a-4029-bc7a-6644a34420bd
2026-01-19 03:08:27,846 - DEBUG - =>VN ctest-mgmt_vn-79097218 has no policy to be verified
2026-01-19 03:08:27,846 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:27,846 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:27,847 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,145 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218?flat'}
2026-01-19 03:08:28,207 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:28,207 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,500 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,558 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218?flat'}
2026-01-19 03:08:28,558 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:28,559 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,858 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:28,922 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218?flat'}
2026-01-19 03:08:28,922 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-79097218 is found in opserver
2026-01-19 03:08:28,943 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:28,954 - DEBUG - VRF ids for VN ctest-mgmt_vn-79097218: {}
2026-01-19 03:08:29,243 - DEBUG - Response for create_network : {'network': {'id': '5e127b87-49f3-417e-85ba-83398cef5bbc', 'name': 'service-left-vn-56004397', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'service-left-vn-56004397'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:29,483 - DEBUG - Response for create_subnet : {'subnet': {'id': '8bec2e00-b1f5-4e76-8089-396ce44cd2b6', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': '5e127b87-49f3-417e-85ba-83398cef5bbc', 'ip_version': 4, 'cidr': '114.243.11.64/26', 'allocation_pools': [{'start': '114.243.11.66', 'end': '114.243.11.126'}], 'gateway_ip': '114.243.11.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.243.11.66', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:29,505 - INFO - Created VN service-left-vn-56004397
2026-01-19 03:08:29,519 - DEBUG - VN service-left-vn-56004397 UUID is 5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,604 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:29,610 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:29,620 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:29,710 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,720 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,729 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:29,739 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:29,746 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5312581b-6a41-4ba1-abe1-ee78017757e1
2026-01-19 03:08:29,753 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:29,753 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,762 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:29,781 - INFO - Verified VN network id 17 for VN 5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,782 - INFO - Verifications in API Server for VN service-left-vn-56004397 passed
2026-01-19 03:08:29,782 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,792 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:29,800 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:29,809 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5312581b-6a41-4ba1-abe1-ee78017757e1
2026-01-19 03:08:29,822 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6778616210137497982', 'uuid-lslong': '9636158635913468860'}, 'enable': 'true', 'created': '2026-01-19T03:08:29', 'last-modified': '2026-01-19T03:08:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398257'}
2026-01-19 03:08:29,827 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:29,832 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6778616210137497982', 'uuid-lslong': '9636158635913468860'}, 'enable': 'true', 'created': '2026-01-19T03:08:29', 'last-modified': '2026-01-19T03:08:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413747'}
2026-01-19 03:08:29,838 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:29,844 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6778616210137497982', 'uuid-lslong': '9636158635913468860'}, 'enable': 'true', 'created': '2026-01-19T03:08:29', 'last-modified': '2026-01-19T03:08:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424494'}
2026-01-19 03:08:29,850 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:29,850 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-56004397 passed
2026-01-19 03:08:29,850 - DEBUG - ====Verifying policy data for service-left-vn-56004397 in API_Server ======
2026-01-19 03:08:29,850 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:29,856 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:29,867 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:29,955 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/5e127b87-49f3-417e-85ba-83398cef5bbc
2026-01-19 03:08:29,966 - DEBUG - =>VN service-left-vn-56004397 has no policy to be verified
2026-01-19 03:08:29,967 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:29,967 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:29,967 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:30,044 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397?flat'}
2026-01-19 03:08:30,045 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:30,045 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:30,100 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397?flat'}
2026-01-19 03:08:30,100 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:30,100 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:30,161 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397?flat'}
2026-01-19 03:08:30,162 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:service-left-vn-56004397 is found in opserver
2026-01-19 03:08:30,185 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:30,195 - DEBUG - VRF ids for VN service-left-vn-56004397: {}
2026-01-19 03:08:30,467 - DEBUG - Response for create_network : {'network': {'id': '53615128-164a-4ec9-9e50-ff93c990c260', 'name': 'service-right-vn-52108659', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'service-right-vn-52108659'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:30,739 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c346b7b6-0622-4ebd-a019-59468e208723', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': '53615128-164a-4ec9-9e50-ff93c990c260', 'ip_version': 4, 'cidr': '32.184.217.192/26', 'allocation_pools': [{'start': '32.184.217.194', 'end': '32.184.217.254'}], 'gateway_ip': '32.184.217.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '32.184.217.194', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:30,763 - INFO - Created VN service-right-vn-52108659
2026-01-19 03:08:30,782 - DEBUG - VN service-right-vn-52108659 UUID is 53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:30,851 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:30,857 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:30,870 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:30,973 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:30,994 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:31,002 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:31,010 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:31,019 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c7841549-4fbc-43c8-bf65-1b9611dbeb7f
2026-01-19 03:08:31,026 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:31,026 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:31,035 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:31,051 - INFO - Verified VN network id 18 for VN 53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:31,051 - INFO - Verifications in API Server for VN service-right-vn-52108659 passed
2026-01-19 03:08:31,051 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:31,061 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:31,072 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:31,081 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/c7841549-4fbc-43c8-bf65-1b9611dbeb7f
2026-01-19 03:08:31,093 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6008172610503462601', 'uuid-lslong': '11407898866336449120'}, 'enable': 'true', 'created': '2026-01-19T03:08:30', 'last-modified': '2026-01-19T03:08:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428150'}
2026-01-19 03:08:31,099 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:31,104 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6008172610503462601', 'uuid-lslong': '11407898866336449120'}, 'enable': 'true', 'created': '2026-01-19T03:08:30', 'last-modified': '2026-01-19T03:08:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435663'}
2026-01-19 03:08:31,110 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:31,116 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6008172610503462601', 'uuid-lslong': '11407898866336449120'}, 'enable': 'true', 'created': '2026-01-19T03:08:30', 'last-modified': '2026-01-19T03:08:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.448419'}
2026-01-19 03:08:31,122 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:31,122 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-52108659 passed
2026-01-19 03:08:31,122 - DEBUG - ====Verifying policy data for service-right-vn-52108659 in API_Server ======
2026-01-19 03:08:31,122 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:31,129 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:31,140 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:31,234 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/53615128-164a-4ec9-9e50-ff93c990c260
2026-01-19 03:08:31,247 - DEBUG - =>VN service-right-vn-52108659 has no policy to be verified
2026-01-19 03:08:31,247 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:31,247 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:31,248 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:31,325 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659?flat'}
2026-01-19 03:08:31,325 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:31,326 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:31,390 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659?flat'}
2026-01-19 03:08:31,390 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:31,390 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:31,453 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659?flat'}
2026-01-19 03:08:31,453 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:service-right-vn-52108659 is found in opserver
2026-01-19 03:08:31,476 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:31,486 - DEBUG - VRF ids for VN service-right-vn-52108659: {}
2026-01-19 03:08:31,559 - DEBUG - Services list from nova: [, , , ]
2026-01-19 03:08:32,685 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1)
2026-01-19 03:08:34,035 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-2)
2026-01-19 03:08:34,035 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-64213179']
2026-01-19 03:08:34,366 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-64213179'] created succesfully
2026-01-19 03:08:34,841 - DEBUG - Image by name tiny_nat_fw not found
2026-01-19 03:08:34,841 - DEBUG - Installing image tiny_nat_fw
2026-01-19 03:08:34,841 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-01-19 03:08:34,841 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:34,841 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:34,841 - DEBUG - Unzip image
2026-01-19 03:08:34,841 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:34,866 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-19 03:08:34,867 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-64213179']
2026-01-19 03:08:34,980 - INFO - Service template: ['default-domain', 'ctest-service_template_1-64213179'] deleted successfully.
2026-01-19 03:08:34,981 - INFO - Skip interface_detach for VM ctest-right_vm-67897999 in state BUILD
2026-01-19 03:08:34,981 - INFO - Deleting VM ctest-right_vm-67897999
2026-01-19 03:08:35,010 - ERROR - VM ctest-right_vm-67897999 has failed to come up
2026-01-19 03:08:35,010 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:35,010 - ERROR - VM ctest-right_vm-67897999 has failed to come up
2026-01-19 03:08:35,010 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:35,113 - INFO - Skip interface_detach for VM ctest-left_vm-74629685 in state BUILD
2026-01-19 03:08:35,113 - INFO - Deleting VM ctest-left_vm-74629685
2026-01-19 03:08:35,113 - ERROR - VM ctest-left_vm-74629685 has failed to come up
2026-01-19 03:08:35,113 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:35,113 - ERROR - VM ctest-left_vm-74629685 has failed to come up
2026-01-19 03:08:35,113 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:35,200 - INFO - Deleting VN service-right-vn-52108659
2026-01-19 03:08:35,242 - DEBUG - VN 53615128-164a-4ec9-9e50-ff93c990c260 still in use: Unable to complete operation on network 53615128-164a-4ec9-9e50-ff93c990c260. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-72c0443d-dfd0-4851-b35c-26caa5e66aba']
2026-01-19 03:08:35,242 - WARNING - Deleting VN service-right-vn-52108659 failed..Will retry
2026-01-19 03:08:37,414 - DEBUG - Response for deleting network ()
2026-01-19 03:08:37,414 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/34835113-bfeb-48c5-a09b-f65a1545f118
2026-01-19 03:08:37,420 - DEBUG - Response Code: 404
2026-01-19 03:08:37,420 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:37,430 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:37,463 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:37,553 - INFO - Validated that VN service-right-vn-52108659 is not found in API Server
2026-01-19 03:08:37,573 - DEBUG - VN service-right-vn-52108659 is not present in Agent 10.0.0.249
2026-01-19 03:08:37,584 - DEBUG - VN service-right-vn-52108659 is not present in Agent 10.0.0.65
2026-01-19 03:08:37,584 - INFO - Validated that VN service-right-vn-52108659 is not in any agent
2026-01-19 03:08:37,619 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-52108659 info
2026-01-19 03:08:37,619 - INFO - Deleting VN service-left-vn-56004397
2026-01-19 03:08:37,778 - DEBUG - Response for deleting network ()
2026-01-19 03:08:37,778 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/f187574d-b2cb-466d-a7b6-6bae3278e67f
2026-01-19 03:08:37,785 - DEBUG - Response Code: 404
2026-01-19 03:08:37,785 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:37,798 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:37,821 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:37,915 - INFO - Validated that VN service-left-vn-56004397 is not found in API Server
2026-01-19 03:08:37,927 - DEBUG - VN service-left-vn-56004397 is not present in Agent 10.0.0.249
2026-01-19 03:08:37,936 - DEBUG - VN service-left-vn-56004397 is not present in Agent 10.0.0.65
2026-01-19 03:08:37,936 - INFO - Validated that VN service-left-vn-56004397 is not in any agent
2026-01-19 03:08:37,983 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-56004397 info
2026-01-19 03:08:37,983 - INFO - Deleting VN ctest-mgmt_vn-79097218
2026-01-19 03:08:38,174 - DEBUG - Response for deleting network ()
2026-01-19 03:08:38,175 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0f77e72a-b092-4ef8-b323-f4ed027b06ca
2026-01-19 03:08:38,184 - DEBUG - Response Code: 404
2026-01-19 03:08:38,184 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:38,192 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:38,248 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:38,339 - INFO - Validated that VN ctest-mgmt_vn-79097218 is not found in API Server
2026-01-19 03:08:38,351 - DEBUG - VN ctest-mgmt_vn-79097218 is not present in Agent 10.0.0.249
2026-01-19 03:08:38,364 - DEBUG - VN ctest-mgmt_vn-79097218 is not present in Agent 10.0.0.65
2026-01-19 03:08:38,364 - INFO - Validated that VN ctest-mgmt_vn-79097218 is not in any agent
2026-01-19 03:08:38,415 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-79097218 info
2026-01-19 03:08:39,717 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:08:39,717 - INFO -
2026-01-19 03:08:39,717 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2026-01-19 03:08:39,717 - INFO - --------------------------------------------------------------------------------
2026-01-19 03:08:39,720 - INFO - ================================================================================
2026-01-19 03:08:39,720 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-01-19 03:08:41,000 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.65': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2026-01-19 03:08:41,000 - INFO - Initial checks done. Running the testcase now
2026-01-19 03:08:41,000 - INFO -
2026-01-19 03:08:41,276 - DEBUG - Response for create_network : {'network': {'id': 'd5a605e0-8020-4207-8b33-c94254e0adf7', 'name': 'ctest-mgmt_vn-59405507', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'ctest-mgmt_vn-59405507'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:41,512 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca92fcf4-35a7-486e-96fc-77ebd3999211', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': 'd5a605e0-8020-4207-8b33-c94254e0adf7', 'ip_version': 4, 'cidr': '29.107.217.0/26', 'allocation_pools': [{'start': '29.107.217.2', 'end': '29.107.217.62'}], 'gateway_ip': '29.107.217.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.107.217.2', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:41,535 - INFO - Created VN ctest-mgmt_vn-59405507
2026-01-19 03:08:41,549 - DEBUG - VN ctest-mgmt_vn-59405507 UUID is d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,620 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:41,626 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:41,638 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:41,740 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,762 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,776 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:41,784 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:41,792 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/08f7bd46-7f81-4954-ae26-75c620ccb418
2026-01-19 03:08:41,800 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:41,800 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,809 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:41,829 - INFO - Verified VN network id 16 for VN d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,829 - INFO - Verifications in API Server for VN ctest-mgmt_vn-59405507 passed
2026-01-19 03:08:41,829 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:41,842 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:41,851 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:41,860 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/08f7bd46-7f81-4954-ae26-75c620ccb418
2026-01-19 03:08:41,872 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15394998837993030151', 'uuid-lslong': '10030582081813261815'}, 'enable': 'true', 'created': '2026-01-19T03:08:41', 'last-modified': '2026-01-19T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403467'}
2026-01-19 03:08:41,878 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:41,884 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15394998837993030151', 'uuid-lslong': '10030582081813261815'}, 'enable': 'true', 'created': '2026-01-19T03:08:41', 'last-modified': '2026-01-19T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417685'}
2026-01-19 03:08:41,890 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:41,896 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15394998837993030151', 'uuid-lslong': '10030582081813261815'}, 'enable': 'true', 'created': '2026-01-19T03:08:41', 'last-modified': '2026-01-19T03:08:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432033'}
2026-01-19 03:08:41,901 - DEBUG - Route Targets: ['target:64512:8000004']
2026-01-19 03:08:41,901 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-59405507 passed
2026-01-19 03:08:41,902 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-59405507 in API_Server ======
2026-01-19 03:08:41,902 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:41,908 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:41,921 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:42,014 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d5a605e0-8020-4207-8b33-c94254e0adf7
2026-01-19 03:08:42,026 - DEBUG - =>VN ctest-mgmt_vn-59405507 has no policy to be verified
2026-01-19 03:08:42,026 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:42,026 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:42,026 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:42,039 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507?flat'}
2026-01-19 03:08:42,039 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:42,039 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:42,052 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507?flat'}
2026-01-19 03:08:42,052 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:42,052 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:42,066 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507?flat'}
2026-01-19 03:08:42,066 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:ctest-mgmt_vn-59405507 is found in opserver
2026-01-19 03:08:42,086 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:42,102 - DEBUG - VRF ids for VN ctest-mgmt_vn-59405507: {}
2026-01-19 03:08:42,371 - DEBUG - Response for create_network : {'network': {'id': '29f06d9f-065c-4fc6-a887-a8550a8a7c55', 'name': 'ctest-left_vn-85462061', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'ctest-left_vn-85462061'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:42,584 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fd0bc246-1596-4f0a-b3c6-4afd6af7c3a0', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': '29f06d9f-065c-4fc6-a887-a8550a8a7c55', 'ip_version': 4, 'cidr': '81.217.213.0/26', 'allocation_pools': [{'start': '81.217.213.2', 'end': '81.217.213.62'}], 'gateway_ip': '81.217.213.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '81.217.213.2', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:42,607 - INFO - Created VN ctest-left_vn-85462061
2026-01-19 03:08:42,619 - DEBUG - VN ctest-left_vn-85462061 UUID is 29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,691 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:42,697 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:42,707 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:42,799 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,813 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,823 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:42,834 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:42,842 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e79157a2-5de0-42db-84a6-2ba57ee954ee
2026-01-19 03:08:42,849 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:42,849 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,859 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:42,876 - INFO - Verified VN network id 17 for VN 29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,876 - INFO - Verifications in API Server for VN ctest-left_vn-85462061 passed
2026-01-19 03:08:42,876 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:42,886 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:42,894 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:42,901 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/e79157a2-5de0-42db-84a6-2ba57ee954ee
2026-01-19 03:08:42,913 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3022035879739543494', 'uuid-lslong': '12143860003430366293'}, 'enable': 'true', 'created': '2026-01-19T03:08:42', 'last-modified': '2026-01-19T03:08:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380208'}
2026-01-19 03:08:42,920 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:42,926 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3022035879739543494', 'uuid-lslong': '12143860003430366293'}, 'enable': 'true', 'created': '2026-01-19T03:08:42', 'last-modified': '2026-01-19T03:08:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.394184'}
2026-01-19 03:08:42,932 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:42,938 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3022035879739543494', 'uuid-lslong': '12143860003430366293'}, 'enable': 'true', 'created': '2026-01-19T03:08:42', 'last-modified': '2026-01-19T03:08:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407968'}
2026-01-19 03:08:42,944 - DEBUG - Route Targets: ['target:64512:8000006']
2026-01-19 03:08:42,944 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-85462061 passed
2026-01-19 03:08:42,944 - DEBUG - ====Verifying policy data for ctest-left_vn-85462061 in API_Server ======
2026-01-19 03:08:42,944 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:42,950 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:42,962 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:43,056 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/29f06d9f-065c-4fc6-a887-a8550a8a7c55
2026-01-19 03:08:43,068 - DEBUG - =>VN ctest-left_vn-85462061 has no policy to be verified
2026-01-19 03:08:43,068 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:43,068 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:43,068 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:43,080 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061?flat'}
2026-01-19 03:08:43,080 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:43,080 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:43,092 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061?flat'}
2026-01-19 03:08:43,092 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:43,092 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:43,106 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061?flat'}
2026-01-19 03:08:43,106 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:ctest-left_vn-85462061 is found in opserver
2026-01-19 03:08:43,128 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:43,139 - DEBUG - VRF ids for VN ctest-left_vn-85462061: {}
2026-01-19 03:08:43,417 - DEBUG - Response for create_network : {'network': {'id': 'fd6bbc4f-3ded-4918-8f28-672e14370738', 'name': 'ctest-right_vn-53682206', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-56748827', 'ctest-right_vn-53682206'], 'port_security_enabled': True, 'description': ''}}
2026-01-19 03:08:43,629 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a1b8edf1-b96f-4436-83d6-f2027a8e5937', 'name': '', 'tenant_id': 'b1e1238b43b84eb98c9bf27ecfc394a9', 'network_id': 'fd6bbc4f-3ded-4918-8f28-672e14370738', 'ip_version': 4, 'cidr': '131.18.220.128/26', 'allocation_pools': [{'start': '131.18.220.130', 'end': '131.18.220.190'}], 'gateway_ip': '131.18.220.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '131.18.220.130', 'tags': [], 'project_id': 'b1e1238b43b84eb98c9bf27ecfc394a9'}}
2026-01-19 03:08:43,649 - INFO - Created VN ctest-right_vn-53682206
2026-01-19 03:08:43,661 - DEBUG - VN ctest-right_vn-53682206 UUID is fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,743 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:43,749 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:43,759 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:43,849 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,861 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,870 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:43,880 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:43,887 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/750a3f26-bf2b-45cd-ba21-14a0476b1ad4
2026-01-19 03:08:43,894 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:43,894 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,904 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:43,922 - INFO - Verified VN network id 18 for VN fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,922 - INFO - Verifications in API Server for VN ctest-right_vn-53682206 passed
2026-01-19 03:08:43,922 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:43,931 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:43,939 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:43,947 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/750a3f26-bf2b-45cd-ba21-14a0476b1ad4
2026-01-19 03:08:43,959 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18260896162631207192', 'uuid-lslong': '10315608394097428280'}, 'enable': 'true', 'created': '2026-01-19T03:08:43', 'last-modified': '2026-01-19T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.385339'}
2026-01-19 03:08:43,967 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:43,972 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18260896162631207192', 'uuid-lslong': '10315608394097428280'}, 'enable': 'true', 'created': '2026-01-19T03:08:43', 'last-modified': '2026-01-19T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388764'}
2026-01-19 03:08:43,978 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:43,983 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-56748827', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18260896162631207192', 'uuid-lslong': '10315608394097428280'}, 'enable': 'true', 'created': '2026-01-19T03:08:43', 'last-modified': '2026-01-19T03:08:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407278'}
2026-01-19 03:08:43,990 - DEBUG - Route Targets: ['target:64512:8000007']
2026-01-19 03:08:43,991 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-53682206 passed
2026-01-19 03:08:43,991 - DEBUG - ====Verifying policy data for ctest-right_vn-53682206 in API_Server ======
2026-01-19 03:08:43,991 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:43,999 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:44,014 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:44,112 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/fd6bbc4f-3ded-4918-8f28-672e14370738
2026-01-19 03:08:44,127 - DEBUG - =>VN ctest-right_vn-53682206 has no policy to be verified
2026-01-19 03:08:44,127 - DEBUG - Verifying the vn in opserver
2026-01-19 03:08:44,128 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206 virtual network link through opserver 10.0.0.27
2026-01-19 03:08:44,128 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-01-19 03:08:44,142 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206?flat'}
2026-01-19 03:08:44,142 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206 virtual network link through opserver 10.0.0.50
2026-01-19 03:08:44,142 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-01-19 03:08:44,155 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206?flat'}
2026-01-19 03:08:44,155 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206 virtual network link through opserver 10.0.0.23
2026-01-19 03:08:44,155 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-01-19 03:08:44,170 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206?flat'}
2026-01-19 03:08:44,170 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-56748827:ctest-right_vn-53682206 is found in opserver
2026-01-19 03:08:44,191 - DEBUG - Do not have enough data to verify VN in agent
2026-01-19 03:08:44,203 - DEBUG - VRF ids for VN ctest-right_vn-53682206: {}
2026-01-19 03:08:45,285 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-2)
2026-01-19 03:08:46,545 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5040-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5040-1)
2026-01-19 03:08:46,546 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-48377999']
2026-01-19 03:08:46,846 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-48377999'] created succesfully
2026-01-19 03:08:47,203 - DEBUG - Image by name tiny_nat_fw not found
2026-01-19 03:08:47,203 - DEBUG - Installing image tiny_nat_fw
2026-01-19 03:08:47,203 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-01-19 03:08:47,203 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:47,203 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:47,203 - DEBUG - Unzip image
2026-01-19 03:08:47,203 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-01-19 03:08:47,221 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-01-19 03:08:47,222 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-48377999']
2026-01-19 03:08:47,318 - INFO - Service template: ['default-domain', 'ctest-service_template_1-48377999'] deleted successfully.
2026-01-19 03:08:47,319 - INFO - Skip interface_detach for VM ctest-right_vm-69891199 in state BUILD
2026-01-19 03:08:47,319 - INFO - Deleting VM ctest-right_vm-69891199
2026-01-19 03:08:47,319 - ERROR - VM ctest-right_vm-69891199 has failed to come up
2026-01-19 03:08:47,319 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:47,319 - ERROR - VM ctest-right_vm-69891199 has failed to come up
2026-01-19 03:08:47,319 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:47,425 - INFO - Skip interface_detach for VM ctest-left_vm-43135217 in state BUILD
2026-01-19 03:08:47,425 - INFO - Deleting VM ctest-left_vm-43135217
2026-01-19 03:08:47,425 - ERROR - VM ctest-left_vm-43135217 has failed to come up
2026-01-19 03:08:47,426 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:47,426 - ERROR - VM ctest-left_vm-43135217 has failed to come up
2026-01-19 03:08:47,426 - ERROR - Nova failed to get host of the VM
2026-01-19 03:08:47,512 - INFO - Deleting VN ctest-right_vn-53682206
2026-01-19 03:08:47,568 - DEBUG - VN fd6bbc4f-3ded-4918-8f28-672e14370738 still in use: Unable to complete operation on network fd6bbc4f-3ded-4918-8f28-672e14370738. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3aad799a-b427-4ab5-98cc-560f9fe0c26d']
2026-01-19 03:08:47,568 - WARNING - Deleting VN ctest-right_vn-53682206 failed..Will retry
2026-01-19 03:08:49,712 - DEBUG - Response for deleting network ()
2026-01-19 03:08:49,712 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6faf46a1-feac-4417-8e67-9d53bad3fd52
2026-01-19 03:08:49,718 - DEBUG - Response Code: 404
2026-01-19 03:08:49,718 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:49,727 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:49,758 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:49,844 - INFO - Validated that VN ctest-right_vn-53682206 is not found in API Server
2026-01-19 03:08:49,855 - DEBUG - VN ctest-right_vn-53682206 is not present in Agent 10.0.0.249
2026-01-19 03:08:49,865 - DEBUG - VN ctest-right_vn-53682206 is not present in Agent 10.0.0.65
2026-01-19 03:08:49,865 - INFO - Validated that VN ctest-right_vn-53682206 is not in any agent
2026-01-19 03:08:49,893 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-53682206 info
2026-01-19 03:08:49,894 - INFO - Deleting VN ctest-left_vn-85462061
2026-01-19 03:08:50,051 - DEBUG - Response for deleting network ()
2026-01-19 03:08:50,051 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/d8adedf9-c6ae-4f57-9044-5bf540f68c7e
2026-01-19 03:08:50,056 - DEBUG - Response Code: 404
2026-01-19 03:08:50,057 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:50,063 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:50,120 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:50,209 - INFO - Validated that VN ctest-left_vn-85462061 is not found in API Server
2026-01-19 03:08:50,220 - DEBUG - VN ctest-left_vn-85462061 is not present in Agent 10.0.0.249
2026-01-19 03:08:50,231 - DEBUG - VN ctest-left_vn-85462061 is not present in Agent 10.0.0.65
2026-01-19 03:08:50,231 - INFO - Validated that VN ctest-left_vn-85462061 is not in any agent
2026-01-19 03:08:50,281 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-85462061 info
2026-01-19 03:08:50,282 - INFO - Deleting VN ctest-mgmt_vn-59405507
2026-01-19 03:08:50,450 - DEBUG - Response for deleting network ()
2026-01-19 03:08:50,450 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/0cfa447e-40a1-4716-a860-7d7f2c8032a4
2026-01-19 03:08:50,462 - DEBUG - Response Code: 404
2026-01-19 03:08:50,462 - DEBUG - Requesting: http://10.0.0.27:8082/domains
2026-01-19 03:08:50,472 - DEBUG - Requesting: http://10.0.0.27:8082/domain/bc123c74-c45e-4d7a-a955-76f59d7a4cc7
2026-01-19 03:08:50,484 - DEBUG - Requesting: http://10.0.0.27:8082/project/b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9
2026-01-19 03:08:50,576 - INFO - Validated that VN ctest-mgmt_vn-59405507 is not found in API Server
2026-01-19 03:08:50,590 - DEBUG - VN ctest-mgmt_vn-59405507 is not present in Agent 10.0.0.249
2026-01-19 03:08:50,603 - DEBUG - VN ctest-mgmt_vn-59405507 is not present in Agent 10.0.0.65
2026-01-19 03:08:50,603 - INFO - Validated that VN ctest-mgmt_vn-59405507 is not in any agent
2026-01-19 03:08:50,635 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-59405507 info
2026-01-19 03:08:51,910 - DEBUG - No XMPP flaps were noticed during the test
2026-01-19 03:08:51,910 - INFO -
2026-01-19 03:08:51,910 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2026-01-19 03:08:51,910 - INFO - --------------------------------------------------------------------------------
2026-01-19 03:08:52,651 - INFO - Deleted project: ctest-TestSvcRegr-56748827, ID : b1e1238b-43b8-4eb9-8c9b-f27ecfc394a9