]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1)
2025-09-15 03:00:13,242 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-03340971']
2025-09-15 03:00:13,543 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-03340971'] created succesfully
2025-09-15 03:00:13,930 - DEBUG - Image by name tiny_nat_fw not found
2025-09-15 03:00:13,930 - DEBUG - Installing image tiny_nat_fw
2025-09-15 03:00:13,930 - 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'"}
2025-09-15 03:00:13,930 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:13,930 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:13,954 - 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
2025-09-15 03:00:14,041 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:14,041 - DEBUG - Unzip image
2025-09-15 03:00:14,041 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:14,074 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-15 03:00:14,076 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-03340971']
2025-09-15 03:00:14,203 - INFO - Service template: ['default-domain', 'ctest-service_template_1-03340971'] deleted successfully.
2025-09-15 03:00:14,204 - INFO - Skip interface_detach for VM ctest-right_vm-78163645 in state BUILD
2025-09-15 03:00:14,204 - INFO - Deleting VM ctest-right_vm-78163645
2025-09-15 03:00:14,233 - ERROR - VM ctest-right_vm-78163645 has failed to come up
2025-09-15 03:00:14,233 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:14,233 - ERROR - VM ctest-right_vm-78163645 has failed to come up
2025-09-15 03:00:14,233 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:14,300 - INFO - Skip interface_detach for VM ctest-left_vm-83630705 in state BUILD
2025-09-15 03:00:14,300 - INFO - Deleting VM ctest-left_vm-83630705
2025-09-15 03:00:14,300 - ERROR - VM ctest-left_vm-83630705 has failed to come up
2025-09-15 03:00:14,300 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:14,300 - ERROR - VM ctest-left_vm-83630705 has failed to come up
2025-09-15 03:00:14,300 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:14,385 - INFO - Deleting VN ctest-right_vn-04431976
2025-09-15 03:00:14,435 - DEBUG - VN 8117ce95-cce2-4f9e-b14c-2d786b10eaaf still in use: Unable to complete operation on network 8117ce95-cce2-4f9e-b14c-2d786b10eaaf. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-fa681492-3216-4d3a-97f9-360058a0db2f']
2025-09-15 03:00:14,435 - WARNING - Deleting VN ctest-right_vn-04431976 failed..Will retry
2025-09-15 03:00:16,609 - DEBUG - Response for deleting network ()
2025-09-15 03:00:16,610 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/c9b313f3-44ac-488b-96d2-4406e7d615ad
2025-09-15 03:00:16,618 - DEBUG - Response Code: 404
2025-09-15 03:00:16,618 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:16,630 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:16,658 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:16,767 - INFO - Validated that VN ctest-right_vn-04431976 is not found in API Server
2025-09-15 03:00:16,783 - DEBUG - VN ctest-right_vn-04431976 is not present in Agent 10.0.0.19
2025-09-15 03:00:16,795 - DEBUG - VN ctest-right_vn-04431976 is not present in Agent 10.0.0.137
2025-09-15 03:00:16,795 - INFO - Validated that VN ctest-right_vn-04431976 is not in any agent
2025-09-15 03:00:16,830 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-04431976 info
2025-09-15 03:00:16,831 - INFO - Deleting VN ctest-left_vn-41351685
2025-09-15 03:00:17,001 - DEBUG - Response for deleting network ()
2025-09-15 03:00:17,001 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/b235e634-549c-46c1-97f0-dae515224d0b
2025-09-15 03:00:17,009 - DEBUG - Response Code: 404
2025-09-15 03:00:17,010 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:17,017 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:17,029 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:17,123 - INFO - Validated that VN ctest-left_vn-41351685 is not found in API Server
2025-09-15 03:00:17,136 - DEBUG - VN ctest-left_vn-41351685 is not present in Agent 10.0.0.19
2025-09-15 03:00:17,146 - DEBUG - VN ctest-left_vn-41351685 is not present in Agent 10.0.0.137
2025-09-15 03:00:17,146 - INFO - Validated that VN ctest-left_vn-41351685 is not in any agent
2025-09-15 03:00:17,183 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-41351685 info
2025-09-15 03:00:17,184 - INFO - Deleting VN ctest-mgmt_vn-42237596
2025-09-15 03:00:17,337 - DEBUG - Response for deleting network ()
2025-09-15 03:00:17,338 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/cb25d0ff-bc55-4cc3-be89-cd9487d21053
2025-09-15 03:00:17,344 - DEBUG - Response Code: 404
2025-09-15 03:00:17,344 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:17,349 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:17,366 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:17,466 - INFO - Validated that VN ctest-mgmt_vn-42237596 is not found in API Server
2025-09-15 03:00:17,480 - DEBUG - VN ctest-mgmt_vn-42237596 is not present in Agent 10.0.0.19
2025-09-15 03:00:17,491 - DEBUG - VN ctest-mgmt_vn-42237596 is not present in Agent 10.0.0.137
2025-09-15 03:00:17,491 - INFO - Validated that VN ctest-mgmt_vn-42237596 is not in any agent
2025-09-15 03:00:17,520 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-42237596 info
2025-09-15 03:00:17,520 - INFO - Deleting VM ctest-vm-in-third-vn-91830009
2025-09-15 03:00:17,593 - INFO - Deleting VN ctest-third-vn-17235084
2025-09-15 03:00:17,638 - DEBUG - VN 8367a0c8-a87b-4fac-9190-57007721f6d5 still in use: Unable to complete operation on network 8367a0c8-a87b-4fac-9190-57007721f6d5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-672f65b1-5954-4af5-a0ad-7680319a9ae5']
2025-09-15 03:00:17,638 - WARNING - Deleting VN ctest-third-vn-17235084 failed..Will retry
2025-09-15 03:00:19,783 - DEBUG - Response for deleting network ()
2025-09-15 03:00:21,046 - DEBUG - No XMPP flaps were noticed during the test
2025-09-15 03:00:21,046 - INFO -
2025-09-15 03:00:21,046 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-09-15 03:00:21,046 - INFO - --------------------------------------------------------------------------------
2025-09-15 03:00:21,049 - INFO - ================================================================================
2025-09-15 03:00:21,049 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-09-15 03:00:22,330 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.137': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-09-15 03:00:22,330 - INFO - Initial checks done. Running the testcase now
2025-09-15 03:00:22,330 - INFO -
2025-09-15 03:00:22,602 - DEBUG - Response for create_network : {'network': {'id': 'fdfb510b-3e27-4d93-99e2-b0c21516a521', 'name': 'ctest-mgmt_vn-45726139', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-20286782', 'ctest-mgmt_vn-45726139'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:22,839 - DEBUG - Response for create_subnet : {'subnet': {'id': '488ddded-68c0-4031-add8-4367d1b63756', 'name': '', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'network_id': 'fdfb510b-3e27-4d93-99e2-b0c21516a521', 'ip_version': 4, 'cidr': '216.210.68.192/26', 'allocation_pools': [{'start': '216.210.68.194', 'end': '216.210.68.254'}], 'gateway_ip': '216.210.68.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.210.68.194', 'tags': [], 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de'}}
2025-09-15 03:00:22,862 - INFO - Created VN ctest-mgmt_vn-45726139
2025-09-15 03:00:22,879 - DEBUG - VN ctest-mgmt_vn-45726139 UUID is fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:22,951 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:22,957 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:22,969 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:23,066 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,085 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,101 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:23,111 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:23,120 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/ec6f37e6-3128-4bb8-9688-f48cbe2da695
2025-09-15 03:00:23,129 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:23,129 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,140 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:23,157 - INFO - Verified VN network id 11 for VN fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,158 - INFO - Verifications in API Server for VN ctest-mgmt_vn-45726139 passed
2025-09-15 03:00:23,158 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,168 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:23,175 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:23,181 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/ec6f37e6-3128-4bb8-9688-f48cbe2da695
2025-09-15 03:00:23,191 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18301310619479395731', 'uuid-lslong': '11088619580163532065'}, 'enable': 'true', 'created': '2025-09-15T03:00:22', 'last-modified': '2025-09-15T03:00:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402451'}
2025-09-15 03:00:23,196 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:23,201 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18301310619479395731', 'uuid-lslong': '11088619580163532065'}, 'enable': 'true', 'created': '2025-09-15T03:00:22', 'last-modified': '2025-09-15T03:00:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410459'}
2025-09-15 03:00:23,206 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:23,211 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18301310619479395731', 'uuid-lslong': '11088619580163532065'}, 'enable': 'true', 'created': '2025-09-15T03:00:22', 'last-modified': '2025-09-15T03:00:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416419'}
2025-09-15 03:00:23,216 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:23,216 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-45726139 passed
2025-09-15 03:00:23,216 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-45726139 in API_Server ======
2025-09-15 03:00:23,216 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:23,222 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:23,240 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:23,338 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/fdfb510b-3e27-4d93-99e2-b0c21516a521
2025-09-15 03:00:23,360 - DEBUG - =>VN ctest-mgmt_vn-45726139 has no policy to be verified
2025-09-15 03:00:23,361 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:23,361 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:23,361 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:23,374 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139?flat'}
2025-09-15 03:00:23,374 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:23,374 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:23,387 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139?flat'}
2025-09-15 03:00:23,387 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:23,387 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:23,398 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139?flat'}
2025-09-15 03:00:23,398 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-20286782:ctest-mgmt_vn-45726139 is found in opserver
2025-09-15 03:00:23,419 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:23,428 - DEBUG - VRF ids for VN ctest-mgmt_vn-45726139: {}
2025-09-15 03:00:23,675 - DEBUG - Response for create_network : {'network': {'id': 'f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5', 'name': 'ctest-left_vn-69903496', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-20286782', 'ctest-left_vn-69903496'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:23,912 - DEBUG - Response for create_subnet : {'subnet': {'id': '0499733e-5a3d-42f9-bc32-179e302d7ecc', 'name': '', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'network_id': 'f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5', 'ip_version': 4, 'cidr': '174.118.136.0/26', 'allocation_pools': [{'start': '174.118.136.2', 'end': '174.118.136.62'}], 'gateway_ip': '174.118.136.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.118.136.2', 'tags': [], 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de'}}
2025-09-15 03:00:23,944 - INFO - Created VN ctest-left_vn-69903496
2025-09-15 03:00:23,957 - DEBUG - VN ctest-left_vn-69903496 UUID is f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,045 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:24,052 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:24,062 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:24,145 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,160 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,169 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:24,176 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:24,184 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/658a0bf9-6a7e-4b71-8e7e-e6e7aaaaa10b
2025-09-15 03:00:24,191 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:24,191 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,199 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:24,213 - INFO - Verified VN network id 12 for VN f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,213 - INFO - Verifications in API Server for VN ctest-left_vn-69903496 passed
2025-09-15 03:00:24,213 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,221 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:24,227 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:24,234 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/658a0bf9-6a7e-4b71-8e7e-e6e7aaaaa10b
2025-09-15 03:00:24,244 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17837594902154856973', 'uuid-lslong': '13773330354956183525'}, 'enable': 'true', 'created': '2025-09-15T03:00:23', 'last-modified': '2025-09-15T03:00:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.382985'}
2025-09-15 03:00:24,250 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:24,255 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17837594902154856973', 'uuid-lslong': '13773330354956183525'}, 'enable': 'true', 'created': '2025-09-15T03:00:23', 'last-modified': '2025-09-15T03:00:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393978'}
2025-09-15 03:00:24,261 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:24,266 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17837594902154856973', 'uuid-lslong': '13773330354956183525'}, 'enable': 'true', 'created': '2025-09-15T03:00:23', 'last-modified': '2025-09-15T03:00:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400038'}
2025-09-15 03:00:24,272 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:24,272 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-69903496 passed
2025-09-15 03:00:24,272 - DEBUG - ====Verifying policy data for ctest-left_vn-69903496 in API_Server ======
2025-09-15 03:00:24,272 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:24,278 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:24,288 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:24,375 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/f78bde0e-4e42-4a0d-bf24-b2fbcd44a7e5
2025-09-15 03:00:24,387 - DEBUG - =>VN ctest-left_vn-69903496 has no policy to be verified
2025-09-15 03:00:24,387 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:24,387 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:24,387 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:24,399 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496?flat'}
2025-09-15 03:00:24,399 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:24,399 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:24,412 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496?flat'}
2025-09-15 03:00:24,412 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:24,412 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:24,423 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496?flat'}
2025-09-15 03:00:24,423 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-20286782:ctest-left_vn-69903496 is found in opserver
2025-09-15 03:00:24,441 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:24,450 - DEBUG - VRF ids for VN ctest-left_vn-69903496: {}
2025-09-15 03:00:24,698 - DEBUG - Response for create_network : {'network': {'id': '17d6cf2f-9e01-4b45-bd3d-e4eb83cca671', 'name': 'ctest-right_vn-83638142', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-20286782', 'ctest-right_vn-83638142'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:24,903 - DEBUG - Response for create_subnet : {'subnet': {'id': '2fe79e19-e7bd-4ee5-9c57-5c9d8147d667', 'name': '', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'network_id': '17d6cf2f-9e01-4b45-bd3d-e4eb83cca671', 'ip_version': 4, 'cidr': '182.113.1.128/26', 'allocation_pools': [{'start': '182.113.1.130', 'end': '182.113.1.190'}], 'gateway_ip': '182.113.1.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.113.1.130', 'tags': [], 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de'}}
2025-09-15 03:00:24,929 - INFO - Created VN ctest-right_vn-83638142
2025-09-15 03:00:24,938 - DEBUG - VN ctest-right_vn-83638142 UUID is 17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,010 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:25,016 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:25,029 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:25,116 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,130 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,144 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:25,152 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:25,158 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/aa24c1c6-360b-4299-bc8b-be5515425c83
2025-09-15 03:00:25,164 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:25,164 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,171 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:25,185 - INFO - Verified VN network id 13 for VN 17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,185 - INFO - Verifications in API Server for VN ctest-right_vn-83638142 passed
2025-09-15 03:00:25,186 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,194 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:25,202 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:25,209 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/aa24c1c6-360b-4299-bc8b-be5515425c83
2025-09-15 03:00:25,221 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717788111309720389', 'uuid-lslong': '13636306946927404657'}, 'enable': 'true', 'created': '2025-09-15T03:00:24', 'last-modified': '2025-09-15T03:00:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377754'}
2025-09-15 03:00:25,228 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:25,234 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717788111309720389', 'uuid-lslong': '13636306946927404657'}, 'enable': 'true', 'created': '2025-09-15T03:00:24', 'last-modified': '2025-09-15T03:00:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387003'}
2025-09-15 03:00:25,240 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:25,247 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1717788111309720389', 'uuid-lslong': '13636306946927404657'}, 'enable': 'true', 'created': '2025-09-15T03:00:24', 'last-modified': '2025-09-15T03:00:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406059'}
2025-09-15 03:00:25,255 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:25,255 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-83638142 passed
2025-09-15 03:00:25,255 - DEBUG - ====Verifying policy data for ctest-right_vn-83638142 in API_Server ======
2025-09-15 03:00:25,255 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:25,263 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:25,276 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:25,377 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/17d6cf2f-9e01-4b45-bd3d-e4eb83cca671
2025-09-15 03:00:25,388 - DEBUG - =>VN ctest-right_vn-83638142 has no policy to be verified
2025-09-15 03:00:25,388 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:25,388 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:25,388 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:25,399 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142?flat'}
2025-09-15 03:00:25,399 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:25,399 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:25,411 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142?flat'}
2025-09-15 03:00:25,411 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:25,411 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:25,423 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142?flat'}
2025-09-15 03:00:25,423 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-20286782:ctest-right_vn-83638142 is found in opserver
2025-09-15 03:00:25,443 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:25,452 - DEBUG - VRF ids for VN ctest-right_vn-83638142: {}
2025-09-15 03:00:25,729 - DEBUG - Response for create_network : {'network': {'id': '668a7123-01da-4277-ad43-acdf61cb3251', 'name': 'ctest-trans_left_vn-90947358', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-20286782', 'ctest-trans_left_vn-90947358'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:25,998 - DEBUG - Response for create_subnet : {'subnet': {'id': '736e7849-9bf0-42b5-aae3-4f0e7e25caf8', 'name': '', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'network_id': '668a7123-01da-4277-ad43-acdf61cb3251', 'ip_version': 4, 'cidr': '214.206.113.64/26', 'allocation_pools': [{'start': '214.206.113.66', 'end': '214.206.113.126'}], 'gateway_ip': '214.206.113.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.206.113.66', 'tags': [], 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de'}}
2025-09-15 03:00:26,020 - INFO - Created VN ctest-trans_left_vn-90947358
2025-09-15 03:00:26,032 - DEBUG - VN ctest-trans_left_vn-90947358 UUID is 668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,112 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:26,118 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:26,131 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:26,222 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,232 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,241 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:26,248 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:26,255 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/75e7c740-e97d-40ef-840e-629664aff305
2025-09-15 03:00:26,261 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-15 03:00:26,261 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,271 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:26,284 - INFO - Verified VN network id 14 for VN 668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,285 - INFO - Verifications in API Server for VN ctest-trans_left_vn-90947358 passed
2025-09-15 03:00:26,285 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,295 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:26,301 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:26,307 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/75e7c740-e97d-40ef-840e-629664aff305
2025-09-15 03:00:26,319 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7388842533823595127', 'uuid-lslong': '12485012667419538001'}, 'enable': 'true', 'created': '2025-09-15T03:00:25', 'last-modified': '2025-09-15T03:00:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393063'}
2025-09-15 03:00:26,324 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-15 03:00:26,329 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7388842533823595127', 'uuid-lslong': '12485012667419538001'}, 'enable': 'true', 'created': '2025-09-15T03:00:25', 'last-modified': '2025-09-15T03:00:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405366'}
2025-09-15 03:00:26,337 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-15 03:00:26,342 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7388842533823595127', 'uuid-lslong': '12485012667419538001'}, 'enable': 'true', 'created': '2025-09-15T03:00:25', 'last-modified': '2025-09-15T03:00:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.417717'}
2025-09-15 03:00:26,347 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-15 03:00:26,348 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-90947358 passed
2025-09-15 03:00:26,348 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-90947358 in API_Server ======
2025-09-15 03:00:26,348 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:26,354 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:26,364 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:26,470 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/668a7123-01da-4277-ad43-acdf61cb3251
2025-09-15 03:00:26,484 - DEBUG - =>VN ctest-trans_left_vn-90947358 has no policy to be verified
2025-09-15 03:00:26,484 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:26,484 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:26,484 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:26,501 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358?flat'}
2025-09-15 03:00:26,502 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:26,502 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:26,513 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358?flat'}
2025-09-15 03:00:26,513 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:26,513 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:26,525 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358?flat'}
2025-09-15 03:00:26,525 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-20286782:ctest-trans_left_vn-90947358 is found in opserver
2025-09-15 03:00:26,545 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:26,555 - DEBUG - VRF ids for VN ctest-trans_left_vn-90947358: {}
2025-09-15 03:00:26,787 - DEBUG - Response for create_network : {'network': {'id': '47371a35-4741-4f6a-a2a4-49a7525e0919', 'name': 'ctest-trans_right_vn-11021939', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-20286782', 'ctest-trans_right_vn-11021939'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:27,046 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c925551a-0ba8-4076-9bd3-a203d5174eec', 'name': '', 'tenant_id': 'f5d9521b67e44e9f9e5787f69d2bb8de', 'network_id': '47371a35-4741-4f6a-a2a4-49a7525e0919', 'ip_version': 4, 'cidr': '64.248.24.0/26', 'allocation_pools': [{'start': '64.248.24.2', 'end': '64.248.24.62'}], 'gateway_ip': '64.248.24.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '64.248.24.2', 'tags': [], 'project_id': 'f5d9521b67e44e9f9e5787f69d2bb8de'}}
2025-09-15 03:00:27,067 - INFO - Created VN ctest-trans_right_vn-11021939
2025-09-15 03:00:27,081 - DEBUG - VN ctest-trans_right_vn-11021939 UUID is 47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,161 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:27,167 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:27,179 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:27,271 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,286 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,298 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:27,306 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:27,313 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a5f65e72-1e60-4c41-9ad9-dc1238c62d59
2025-09-15 03:00:27,321 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-15 03:00:27,322 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,331 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:27,349 - INFO - Verified VN network id 15 for VN 47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,349 - INFO - Verifications in API Server for VN ctest-trans_right_vn-11021939 passed
2025-09-15 03:00:27,349 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,367 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:27,375 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:27,382 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/a5f65e72-1e60-4c41-9ad9-dc1238c62d59
2025-09-15 03:00:27,395 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5131599116543020906', 'uuid-lslong': '11719573113315133721'}, 'enable': 'true', 'created': '2025-09-15T03:00:26', 'last-modified': '2025-09-15T03:00:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405541'}
2025-09-15 03:00:27,402 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-15 03:00:27,408 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5131599116543020906', 'uuid-lslong': '11719573113315133721'}, 'enable': 'true', 'created': '2025-09-15T03:00:26', 'last-modified': '2025-09-15T03:00:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422081'}
2025-09-15 03:00:27,413 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-15 03:00:27,418 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-20286782', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5131599116543020906', 'uuid-lslong': '11719573113315133721'}, 'enable': 'true', 'created': '2025-09-15T03:00:26', 'last-modified': '2025-09-15T03:00:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.434235'}
2025-09-15 03:00:27,424 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-15 03:00:27,424 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-11021939 passed
2025-09-15 03:00:27,424 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-11021939 in API_Server ======
2025-09-15 03:00:27,424 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:27,431 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:27,444 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:27,553 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/47371a35-4741-4f6a-a2a4-49a7525e0919
2025-09-15 03:00:27,563 - DEBUG - =>VN ctest-trans_right_vn-11021939 has no policy to be verified
2025-09-15 03:00:27,563 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:27,563 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:27,563 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:27,575 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939?flat'}
2025-09-15 03:00:27,575 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:27,575 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:27,590 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939?flat'}
2025-09-15 03:00:27,590 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:27,590 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:27,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939?flat'}
2025-09-15 03:00:27,601 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-20286782:ctest-trans_right_vn-11021939 is found in opserver
2025-09-15 03:00:27,621 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:27,630 - DEBUG - VRF ids for VN ctest-trans_right_vn-11021939: {}
2025-09-15 03:00:28,666 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1)
2025-09-15 03:00:29,925 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2)
2025-09-15 03:00:29,926 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-61203304']
2025-09-15 03:00:30,230 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-61203304'] created succesfully
2025-09-15 03:00:30,585 - DEBUG - Image by name tiny_trans_fw not found
2025-09-15 03:00:30,585 - DEBUG - Installing image tiny_trans_fw
2025-09-15 03:00:30,585 - 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'}
2025-09-15 03:00:30,585 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-15 03:00:30,586 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-15 03:00:30,609 - 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
2025-09-15 03:00:30,648 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-15 03:00:30,648 - DEBUG - Unzip image
2025-09-15 03:00:30,649 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-15 03:00:30,674 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-09-15 03:00:30,675 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-61203304']
2025-09-15 03:00:30,771 - INFO - Service template: ['default-domain', 'ctest-service_template_1-61203304'] deleted successfully.
2025-09-15 03:00:30,772 - INFO - Skip interface_detach for VM ctest-right_vm-41068358 in state BUILD
2025-09-15 03:00:30,772 - INFO - Deleting VM ctest-right_vm-41068358
2025-09-15 03:00:30,772 - ERROR - VM ctest-right_vm-41068358 has failed to come up
2025-09-15 03:00:30,772 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:30,772 - ERROR - VM ctest-right_vm-41068358 has failed to come up
2025-09-15 03:00:30,772 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:30,849 - INFO - Skip interface_detach for VM ctest-left_vm-01218429 in state BUILD
2025-09-15 03:00:30,849 - INFO - Deleting VM ctest-left_vm-01218429
2025-09-15 03:00:30,850 - ERROR - VM ctest-left_vm-01218429 has failed to come up
2025-09-15 03:00:30,850 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:30,850 - ERROR - VM ctest-left_vm-01218429 has failed to come up
2025-09-15 03:00:30,850 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:30,926 - INFO - Deleting VN ctest-trans_right_vn-11021939
2025-09-15 03:00:31,063 - DEBUG - Response for deleting network ()
2025-09-15 03:00:31,063 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/980aac5c-63da-4c6b-ba6d-e986d51cde81
2025-09-15 03:00:31,068 - DEBUG - Response Code: 404
2025-09-15 03:00:31,068 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:31,074 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:31,096 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:31,196 - INFO - Validated that VN ctest-trans_right_vn-11021939 is not found in API Server
2025-09-15 03:00:31,213 - DEBUG - VN ctest-trans_right_vn-11021939 is not present in Agent 10.0.0.19
2025-09-15 03:00:31,246 - DEBUG - VN ctest-trans_right_vn-11021939 is not present in Agent 10.0.0.137
2025-09-15 03:00:31,246 - INFO - Validated that VN ctest-trans_right_vn-11021939 is not in any agent
2025-09-15 03:00:31,281 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-11021939 info
2025-09-15 03:00:31,281 - INFO - Deleting VN ctest-trans_left_vn-90947358
2025-09-15 03:00:31,448 - DEBUG - Response for deleting network ()
2025-09-15 03:00:31,448 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/e9f63b5c-f92a-455f-b458-4bafcb020cce
2025-09-15 03:00:31,453 - DEBUG - Response Code: 404
2025-09-15 03:00:31,453 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:31,458 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:31,489 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:31,609 - INFO - Validated that VN ctest-trans_left_vn-90947358 is not found in API Server
2025-09-15 03:00:31,621 - DEBUG - VN ctest-trans_left_vn-90947358 is not present in Agent 10.0.0.19
2025-09-15 03:00:31,663 - DEBUG - VN ctest-trans_left_vn-90947358 is not present in Agent 10.0.0.137
2025-09-15 03:00:31,663 - INFO - Validated that VN ctest-trans_left_vn-90947358 is not in any agent
2025-09-15 03:00:31,717 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-90947358 info
2025-09-15 03:00:31,717 - INFO - Deleting VN ctest-right_vn-83638142
2025-09-15 03:00:31,779 - DEBUG - VN 17d6cf2f-9e01-4b45-bd3d-e4eb83cca671 still in use: Unable to complete operation on network 17d6cf2f-9e01-4b45-bd3d-e4eb83cca671. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7608396e-cace-40aa-a444-8c1621ada7f6']
2025-09-15 03:00:31,779 - WARNING - Deleting VN ctest-right_vn-83638142 failed..Will retry
2025-09-15 03:00:33,960 - DEBUG - Response for deleting network ()
2025-09-15 03:00:33,960 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/862fe243-0f54-449b-b5be-bcb822cdabbe
2025-09-15 03:00:33,966 - DEBUG - Response Code: 404
2025-09-15 03:00:33,966 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:33,978 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:34,037 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:34,128 - INFO - Validated that VN ctest-right_vn-83638142 is not found in API Server
2025-09-15 03:00:34,138 - DEBUG - VN ctest-right_vn-83638142 is not present in Agent 10.0.0.19
2025-09-15 03:00:34,148 - DEBUG - VN ctest-right_vn-83638142 is not present in Agent 10.0.0.137
2025-09-15 03:00:34,148 - INFO - Validated that VN ctest-right_vn-83638142 is not in any agent
2025-09-15 03:00:34,176 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-83638142 info
2025-09-15 03:00:34,176 - INFO - Deleting VN ctest-left_vn-69903496
2025-09-15 03:00:34,346 - DEBUG - Response for deleting network ()
2025-09-15 03:00:34,346 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/41a94e27-b04c-43f4-a9d6-d0d25bc4624b
2025-09-15 03:00:34,352 - DEBUG - Response Code: 404
2025-09-15 03:00:34,352 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:34,358 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:34,388 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:34,476 - INFO - Validated that VN ctest-left_vn-69903496 is not found in API Server
2025-09-15 03:00:34,489 - DEBUG - VN ctest-left_vn-69903496 is not present in Agent 10.0.0.19
2025-09-15 03:00:34,499 - DEBUG - VN ctest-left_vn-69903496 is not present in Agent 10.0.0.137
2025-09-15 03:00:34,499 - INFO - Validated that VN ctest-left_vn-69903496 is not in any agent
2025-09-15 03:00:34,535 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-69903496 info
2025-09-15 03:00:34,535 - INFO - Deleting VN ctest-mgmt_vn-45726139
2025-09-15 03:00:34,707 - DEBUG - Response for deleting network ()
2025-09-15 03:00:34,707 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/aa29eae4-f64b-4dd9-a9af-ab56d0be646c
2025-09-15 03:00:34,712 - DEBUG - Response Code: 404
2025-09-15 03:00:34,713 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:34,718 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:34,729 - DEBUG - Requesting: http://10.0.0.249:8082/project/f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:34,841 - INFO - Validated that VN ctest-mgmt_vn-45726139 is not found in API Server
2025-09-15 03:00:34,853 - DEBUG - VN ctest-mgmt_vn-45726139 is not present in Agent 10.0.0.19
2025-09-15 03:00:34,863 - DEBUG - VN ctest-mgmt_vn-45726139 is not present in Agent 10.0.0.137
2025-09-15 03:00:34,863 - INFO - Validated that VN ctest-mgmt_vn-45726139 is not in any agent
2025-09-15 03:00:34,891 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-45726139 info
2025-09-15 03:00:36,157 - DEBUG - No XMPP flaps were noticed during the test
2025-09-15 03:00:36,157 - INFO -
2025-09-15 03:00:36,157 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-09-15 03:00:36,157 - INFO - --------------------------------------------------------------------------------
2025-09-15 03:00:36,930 - INFO - Deleted project: ctest-TestSvcRegr-20286782, ID : f5d9521b-67e4-4e9f-9e57-87f69d2bb8de
2025-09-15 03:00:37,089 - INFO - Domain Default found not creating
2025-09-15 03:00:37,238 - INFO - Project ctest-TestSvcRegr-40834452 not found, creating it
2025-09-15 03:00:37,768 - INFO - Created Project:ctest-TestSvcRegr-40834452, ID : fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:39,259 - INFO - ================================================================================
2025-09-15 03:00:39,259 - INFO - STARTING TEST : test_svc_CEM_22032
2025-09-15 03:00:40,527 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.137': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-09-15 03:00:40,527 - INFO - Initial checks done. Running the testcase now
2025-09-15 03:00:40,527 - INFO -
2025-09-15 03:00:41,172 - DEBUG - Response for create_network : {'network': {'id': '35572a65-72ae-4f70-a1de-15ae4fd9010e', 'name': 'ctest-mgmt_vn-98980715', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'ctest-mgmt_vn-98980715'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:41,448 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ba136fdb-96aa-451e-9584-09c16dd58508', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '35572a65-72ae-4f70-a1de-15ae4fd9010e', 'ip_version': 4, 'cidr': '41.137.13.192/26', 'allocation_pools': [{'start': '41.137.13.194', 'end': '41.137.13.254'}], 'gateway_ip': '41.137.13.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '41.137.13.194', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:41,471 - INFO - Created VN ctest-mgmt_vn-98980715
2025-09-15 03:00:41,481 - DEBUG - VN ctest-mgmt_vn-98980715 UUID is 35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:41,551 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:41,848 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:41,879 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:41,895 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:42,001 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,016 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,027 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:42,035 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:42,042 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/50fbbbf1-0ea1-473d-af1a-d095a11b8ef4
2025-09-15 03:00:42,048 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:42,048 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,056 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:42,069 - INFO - Verified VN network id 11 for VN 35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,069 - INFO - Verifications in API Server for VN ctest-mgmt_vn-98980715 passed
2025-09-15 03:00:42,070 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,079 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:42,086 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:42,093 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/50fbbbf1-0ea1-473d-af1a-d095a11b8ef4
2025-09-15 03:00:42,105 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3843587422188097392', 'uuid-lslong': '11663783923344277774'}, 'enable': 'true', 'created': '2025-09-15T03:00:41', 'last-modified': '2025-09-15T03:00:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.707636'}
2025-09-15 03:00:42,110 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:42,116 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3843587422188097392', 'uuid-lslong': '11663783923344277774'}, 'enable': 'true', 'created': '2025-09-15T03:00:41', 'last-modified': '2025-09-15T03:00:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.717196'}
2025-09-15 03:00:42,121 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:42,126 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3843587422188097392', 'uuid-lslong': '11663783923344277774'}, 'enable': 'true', 'created': '2025-09-15T03:00:41', 'last-modified': '2025-09-15T03:00:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.729810'}
2025-09-15 03:00:42,130 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:42,131 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-98980715 passed
2025-09-15 03:00:42,131 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-98980715 in API_Server ======
2025-09-15 03:00:42,131 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:42,137 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:42,147 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:42,243 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/35572a65-72ae-4f70-a1de-15ae4fd9010e
2025-09-15 03:00:42,255 - DEBUG - =>VN ctest-mgmt_vn-98980715 has no policy to be verified
2025-09-15 03:00:42,255 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:42,255 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:42,255 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:42,546 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:42,600 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715?flat'}
2025-09-15 03:00:42,600 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:42,600 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:42,898 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:42,956 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715?flat'}
2025-09-15 03:00:42,956 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:42,956 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:43,250 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:43,304 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715?flat'}
2025-09-15 03:00:43,304 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-98980715 is found in opserver
2025-09-15 03:00:43,324 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:43,332 - DEBUG - VRF ids for VN ctest-mgmt_vn-98980715: {}
2025-09-15 03:00:43,615 - DEBUG - Response for create_network : {'network': {'id': '3427d111-da50-45a2-b66e-fe2d6e636d63', 'name': 'service-left-vn-42276476', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'service-left-vn-42276476'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:43,950 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ac73bb35-c288-4ba7-88c7-0d7f981334c0', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '3427d111-da50-45a2-b66e-fe2d6e636d63', 'ip_version': 4, 'cidr': '176.35.88.64/26', 'allocation_pools': [{'start': '176.35.88.66', 'end': '176.35.88.126'}], 'gateway_ip': '176.35.88.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '176.35.88.66', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:44,024 - INFO - Created VN service-left-vn-42276476
2025-09-15 03:00:44,050 - DEBUG - VN service-left-vn-42276476 UUID is 3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,185 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:44,230 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:44,353 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:44,465 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,492 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,504 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:44,519 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:44,535 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/e4430593-fb4d-44b7-b057-a2e0504c0e3d
2025-09-15 03:00:44,547 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:44,547 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,561 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:44,579 - INFO - Verified VN network id 12 for VN 3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,579 - INFO - Verifications in API Server for VN service-left-vn-42276476 passed
2025-09-15 03:00:44,579 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,590 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:44,598 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:44,606 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/e4430593-fb4d-44b7-b057-a2e0504c0e3d
2025-09-15 03:00:44,624 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3758202288671311266', 'uuid-lslong': '13145723833420049763'}, 'enable': 'true', 'created': '2025-09-15T03:00:43', 'last-modified': '2025-09-15T03:00:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.748129'}
2025-09-15 03:00:44,633 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:44,641 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3758202288671311266', 'uuid-lslong': '13145723833420049763'}, 'enable': 'true', 'created': '2025-09-15T03:00:43', 'last-modified': '2025-09-15T03:00:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.768400'}
2025-09-15 03:00:44,649 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:44,657 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3758202288671311266', 'uuid-lslong': '13145723833420049763'}, 'enable': 'true', 'created': '2025-09-15T03:00:43', 'last-modified': '2025-09-15T03:00:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.784692'}
2025-09-15 03:00:44,665 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:44,665 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-42276476 passed
2025-09-15 03:00:44,665 - DEBUG - ====Verifying policy data for service-left-vn-42276476 in API_Server ======
2025-09-15 03:00:44,665 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:44,674 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:44,689 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:44,781 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3427d111-da50-45a2-b66e-fe2d6e636d63
2025-09-15 03:00:44,793 - DEBUG - =>VN service-left-vn-42276476 has no policy to be verified
2025-09-15 03:00:44,793 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:44,793 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:44,793 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:44,849 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476?flat'}
2025-09-15 03:00:44,849 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:44,849 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:44,904 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476?flat'}
2025-09-15 03:00:44,904 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:44,904 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:44,958 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476?flat'}
2025-09-15 03:00:44,958 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:service-left-vn-42276476 is found in opserver
2025-09-15 03:00:44,980 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:44,992 - DEBUG - VRF ids for VN service-left-vn-42276476: {}
2025-09-15 03:00:45,255 - DEBUG - Response for create_network : {'network': {'id': '72c30b06-b41e-44e5-a007-2a9b47626e6f', 'name': 'service-right-vn-22707206', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'service-right-vn-22707206'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:45,515 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd1bb485-6963-4a9d-8fa4-3dedecb0180e', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '72c30b06-b41e-44e5-a007-2a9b47626e6f', 'ip_version': 4, 'cidr': '99.92.45.192/26', 'allocation_pools': [{'start': '99.92.45.194', 'end': '99.92.45.254'}], 'gateway_ip': '99.92.45.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.92.45.194', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:45,541 - INFO - Created VN service-right-vn-22707206
2025-09-15 03:00:45,555 - DEBUG - VN service-right-vn-22707206 UUID is 72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,624 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:45,630 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:45,641 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:45,733 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,744 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,752 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:45,759 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:45,766 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3af1461c-ec24-4f7e-bb0b-650dc7587558
2025-09-15 03:00:45,773 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:45,773 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,781 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:45,799 - INFO - Verified VN network id 13 for VN 72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,799 - INFO - Verifications in API Server for VN service-right-vn-22707206 passed
2025-09-15 03:00:45,799 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,809 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:45,816 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:45,823 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3af1461c-ec24-4f7e-bb0b-650dc7587558
2025-09-15 03:00:45,835 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8269465464201954533', 'uuid-lslong': '11531232217311374959'}, 'enable': 'true', 'created': '2025-09-15T03:00:45', 'last-modified': '2025-09-15T03:00:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369763'}
2025-09-15 03:00:45,841 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:45,846 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8269465464201954533', 'uuid-lslong': '11531232217311374959'}, 'enable': 'true', 'created': '2025-09-15T03:00:45', 'last-modified': '2025-09-15T03:00:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.378114'}
2025-09-15 03:00:45,853 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:45,858 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8269465464201954533', 'uuid-lslong': '11531232217311374959'}, 'enable': 'true', 'created': '2025-09-15T03:00:45', 'last-modified': '2025-09-15T03:00:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388429'}
2025-09-15 03:00:45,864 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:45,864 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-22707206 passed
2025-09-15 03:00:45,865 - DEBUG - ====Verifying policy data for service-right-vn-22707206 in API_Server ======
2025-09-15 03:00:45,865 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:45,871 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:45,882 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:45,973 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/72c30b06-b41e-44e5-a007-2a9b47626e6f
2025-09-15 03:00:45,983 - DEBUG - =>VN service-right-vn-22707206 has no policy to be verified
2025-09-15 03:00:45,983 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:45,983 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:45,983 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:46,035 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206?flat'}
2025-09-15 03:00:46,035 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:46,035 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:46,086 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206?flat'}
2025-09-15 03:00:46,086 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:46,086 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:46,140 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206?flat'}
2025-09-15 03:00:46,140 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:service-right-vn-22707206 is found in opserver
2025-09-15 03:00:46,161 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:46,174 - DEBUG - VRF ids for VN service-right-vn-22707206: {}
2025-09-15 03:00:46,236 - DEBUG - Services list from nova: [, , , ]
2025-09-15 03:00:47,245 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1)
2025-09-15 03:00:48,568 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2)
2025-09-15 03:00:48,568 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-29447380']
2025-09-15 03:00:48,871 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-29447380'] created succesfully
2025-09-15 03:00:49,245 - DEBUG - Image by name tiny_nat_fw not found
2025-09-15 03:00:49,245 - DEBUG - Installing image tiny_nat_fw
2025-09-15 03:00:49,245 - 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'"}
2025-09-15 03:00:49,245 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:49,245 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:49,245 - DEBUG - Unzip image
2025-09-15 03:00:49,246 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:00:49,272 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-15 03:00:49,274 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-29447380']
2025-09-15 03:00:49,387 - INFO - Service template: ['default-domain', 'ctest-service_template_1-29447380'] deleted successfully.
2025-09-15 03:00:49,388 - INFO - Skip interface_detach for VM ctest-right_vm-70690509 in state BUILD
2025-09-15 03:00:49,388 - INFO - Deleting VM ctest-right_vm-70690509
2025-09-15 03:00:49,427 - ERROR - VM ctest-right_vm-70690509 has failed to come up
2025-09-15 03:00:49,427 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:49,427 - ERROR - VM ctest-right_vm-70690509 has failed to come up
2025-09-15 03:00:49,427 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:49,519 - INFO - Skip interface_detach for VM ctest-left_vm-15350349 in state BUILD
2025-09-15 03:00:49,519 - INFO - Deleting VM ctest-left_vm-15350349
2025-09-15 03:00:49,519 - ERROR - VM ctest-left_vm-15350349 has failed to come up
2025-09-15 03:00:49,519 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:49,519 - ERROR - VM ctest-left_vm-15350349 has failed to come up
2025-09-15 03:00:49,519 - ERROR - Nova failed to get host of the VM
2025-09-15 03:00:49,786 - INFO - Deleting VN service-right-vn-22707206
2025-09-15 03:00:49,839 - DEBUG - VN 72c30b06-b41e-44e5-a007-2a9b47626e6f still in use: Unable to complete operation on network 72c30b06-b41e-44e5-a007-2a9b47626e6f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-edd7214e-edbd-40b7-b6eb-e26cd38e578f']
2025-09-15 03:00:49,840 - WARNING - Deleting VN service-right-vn-22707206 failed..Will retry
2025-09-15 03:00:52,029 - DEBUG - Response for deleting network ()
2025-09-15 03:00:52,029 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/f67c1751-6bf0-4af5-b682-aaa6d80f244e
2025-09-15 03:00:52,035 - DEBUG - Response Code: 404
2025-09-15 03:00:52,035 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:52,059 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:52,073 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:52,177 - INFO - Validated that VN service-right-vn-22707206 is not found in API Server
2025-09-15 03:00:52,187 - DEBUG - VN service-right-vn-22707206 is not present in Agent 10.0.0.19
2025-09-15 03:00:52,198 - DEBUG - VN service-right-vn-22707206 is not present in Agent 10.0.0.137
2025-09-15 03:00:52,198 - INFO - Validated that VN service-right-vn-22707206 is not in any agent
2025-09-15 03:00:52,227 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-22707206 info
2025-09-15 03:00:52,227 - INFO - Deleting VN service-left-vn-42276476
2025-09-15 03:00:52,379 - DEBUG - Response for deleting network ()
2025-09-15 03:00:52,379 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/058c57a0-28e7-44aa-a527-818fe988073a
2025-09-15 03:00:52,386 - DEBUG - Response Code: 404
2025-09-15 03:00:52,386 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:52,391 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:52,420 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:52,513 - INFO - Validated that VN service-left-vn-42276476 is not found in API Server
2025-09-15 03:00:52,525 - DEBUG - VN service-left-vn-42276476 is not present in Agent 10.0.0.19
2025-09-15 03:00:52,535 - DEBUG - VN service-left-vn-42276476 is not present in Agent 10.0.0.137
2025-09-15 03:00:52,535 - INFO - Validated that VN service-left-vn-42276476 is not in any agent
2025-09-15 03:00:52,564 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-42276476 info
2025-09-15 03:00:52,564 - INFO - Deleting VN ctest-mgmt_vn-98980715
2025-09-15 03:00:52,716 - DEBUG - Response for deleting network ()
2025-09-15 03:00:52,716 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/d8f262cd-13a6-4099-915a-8d0566574cc2
2025-09-15 03:00:52,721 - DEBUG - Response Code: 404
2025-09-15 03:00:52,721 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:52,733 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:52,789 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:52,896 - INFO - Validated that VN ctest-mgmt_vn-98980715 is not found in API Server
2025-09-15 03:00:52,906 - DEBUG - VN ctest-mgmt_vn-98980715 is not present in Agent 10.0.0.19
2025-09-15 03:00:52,917 - DEBUG - VN ctest-mgmt_vn-98980715 is not present in Agent 10.0.0.137
2025-09-15 03:00:52,917 - INFO - Validated that VN ctest-mgmt_vn-98980715 is not in any agent
2025-09-15 03:00:52,949 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-98980715 info
2025-09-15 03:00:54,212 - DEBUG - No XMPP flaps were noticed during the test
2025-09-15 03:00:54,212 - INFO -
2025-09-15 03:00:54,212 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-09-15 03:00:54,212 - INFO - --------------------------------------------------------------------------------
2025-09-15 03:00:54,215 - INFO - ================================================================================
2025-09-15 03:00:54,215 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-09-15 03:00:55,491 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.137': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-09-15 03:00:55,491 - INFO - Initial checks done. Running the testcase now
2025-09-15 03:00:55,491 - INFO -
2025-09-15 03:00:55,757 - DEBUG - Response for create_network : {'network': {'id': '41a23d87-4ee8-48ca-9d18-4bd9e7ca073b', 'name': 'ctest-mgmt_vn-96996452', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'ctest-mgmt_vn-96996452'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:56,007 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fb1cc061-190b-406d-9653-089928f18035', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '41a23d87-4ee8-48ca-9d18-4bd9e7ca073b', 'ip_version': 4, 'cidr': '105.24.154.192/26', 'allocation_pools': [{'start': '105.24.154.194', 'end': '105.24.154.254'}], 'gateway_ip': '105.24.154.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '105.24.154.194', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:56,029 - INFO - Created VN ctest-mgmt_vn-96996452
2025-09-15 03:00:56,041 - DEBUG - VN ctest-mgmt_vn-96996452 UUID is 41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,107 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:56,112 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:56,122 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:56,208 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,220 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,228 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:00:56,235 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:00:56,242 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/4317c733-122f-4d69-9d26-cb225ad9fd08
2025-09-15 03:00:56,249 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:56,249 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,257 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:00:56,273 - INFO - Verified VN network id 11 for VN 41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,273 - INFO - Verifications in API Server for VN ctest-mgmt_vn-96996452 passed
2025-09-15 03:00:56,273 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,282 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:00:56,288 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:00:56,294 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/4317c733-122f-4d69-9d26-cb225ad9fd08
2025-09-15 03:00:56,304 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4729410210046167242', 'uuid-lslong': '11319881062664505147'}, 'enable': 'true', 'created': '2025-09-15T03:00:55', 'last-modified': '2025-09-15T03:00:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.354646'}
2025-09-15 03:00:56,310 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:56,316 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4729410210046167242', 'uuid-lslong': '11319881062664505147'}, 'enable': 'true', 'created': '2025-09-15T03:00:55', 'last-modified': '2025-09-15T03:00:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364461'}
2025-09-15 03:00:56,322 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:56,327 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4729410210046167242', 'uuid-lslong': '11319881062664505147'}, 'enable': 'true', 'created': '2025-09-15T03:00:55', 'last-modified': '2025-09-15T03:00:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.382246'}
2025-09-15 03:00:56,332 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-15 03:00:56,332 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-96996452 passed
2025-09-15 03:00:56,333 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-96996452 in API_Server ======
2025-09-15 03:00:56,333 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:56,339 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:56,349 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:56,432 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/41a23d87-4ee8-48ca-9d18-4bd9e7ca073b
2025-09-15 03:00:56,451 - DEBUG - =>VN ctest-mgmt_vn-96996452 has no policy to be verified
2025-09-15 03:00:56,451 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:56,451 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:56,451 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:56,463 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452?flat'}
2025-09-15 03:00:56,463 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:56,463 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:56,475 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452?flat'}
2025-09-15 03:00:56,475 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:56,475 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:56,487 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452?flat'}
2025-09-15 03:00:56,487 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:ctest-mgmt_vn-96996452 is found in opserver
2025-09-15 03:00:56,509 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:56,529 - DEBUG - VRF ids for VN ctest-mgmt_vn-96996452: {}
2025-09-15 03:00:56,775 - DEBUG - Response for create_network : {'network': {'id': '4e3659e4-5219-4c1a-90e6-5fe466522755', 'name': 'ctest-left_vn-47971134', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'ctest-left_vn-47971134'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:56,992 - DEBUG - Response for create_subnet : {'subnet': {'id': '96013a7d-097a-438e-88ed-accbc87b1f99', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '4e3659e4-5219-4c1a-90e6-5fe466522755', 'ip_version': 4, 'cidr': '2.41.125.0/26', 'allocation_pools': [{'start': '2.41.125.2', 'end': '2.41.125.62'}], 'gateway_ip': '2.41.125.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2.41.125.2', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:57,013 - INFO - Created VN ctest-left_vn-47971134
2025-09-15 03:00:57,025 - DEBUG - VN ctest-left_vn-47971134 UUID is 4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,101 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:57,108 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:57,121 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:57,222 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,235 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,245 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:00:57,252 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:00:57,258 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3e5a66a2-e254-4747-9a68-15eebcd92b2e
2025-09-15 03:00:57,265 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:57,265 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,274 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:00:57,288 - INFO - Verified VN network id 12 for VN 4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,289 - INFO - Verifications in API Server for VN ctest-left_vn-47971134 passed
2025-09-15 03:00:57,289 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,298 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:00:57,305 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:00:57,311 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/3e5a66a2-e254-4747-9a68-15eebcd92b2e
2025-09-15 03:00:57,323 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5635790820865559578', 'uuid-lslong': '10441138220678915925'}, 'enable': 'true', 'created': '2025-09-15T03:00:56', 'last-modified': '2025-09-15T03:00:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388084'}
2025-09-15 03:00:57,329 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:57,335 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5635790820865559578', 'uuid-lslong': '10441138220678915925'}, 'enable': 'true', 'created': '2025-09-15T03:00:56', 'last-modified': '2025-09-15T03:00:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399778'}
2025-09-15 03:00:57,340 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:57,346 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5635790820865559578', 'uuid-lslong': '10441138220678915925'}, 'enable': 'true', 'created': '2025-09-15T03:00:56', 'last-modified': '2025-09-15T03:00:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405869'}
2025-09-15 03:00:57,351 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-15 03:00:57,351 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-47971134 passed
2025-09-15 03:00:57,351 - DEBUG - ====Verifying policy data for ctest-left_vn-47971134 in API_Server ======
2025-09-15 03:00:57,351 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:57,357 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:57,369 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:57,456 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4e3659e4-5219-4c1a-90e6-5fe466522755
2025-09-15 03:00:57,465 - DEBUG - =>VN ctest-left_vn-47971134 has no policy to be verified
2025-09-15 03:00:57,465 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:57,465 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:57,465 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:57,476 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134?flat'}
2025-09-15 03:00:57,476 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:57,477 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:57,489 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134?flat'}
2025-09-15 03:00:57,489 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:57,489 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:57,501 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134?flat'}
2025-09-15 03:00:57,501 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:ctest-left_vn-47971134 is found in opserver
2025-09-15 03:00:57,522 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:57,531 - DEBUG - VRF ids for VN ctest-left_vn-47971134: {}
2025-09-15 03:00:57,790 - DEBUG - Response for create_network : {'network': {'id': '9c024af6-f4f3-49f6-b2d0-40ae5f27e801', 'name': 'ctest-right_vn-71126388', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'project_id': 'fd2b749b642446469baf31a6d5a3c529', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-40834452', 'ctest-right_vn-71126388'], 'port_security_enabled': True, 'description': ''}}
2025-09-15 03:00:58,017 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c2f45506-fccf-4738-9d6b-2532df9d5fb7', 'name': '', 'tenant_id': 'fd2b749b642446469baf31a6d5a3c529', 'network_id': '9c024af6-f4f3-49f6-b2d0-40ae5f27e801', 'ip_version': 4, 'cidr': '47.182.199.64/26', 'allocation_pools': [{'start': '47.182.199.66', 'end': '47.182.199.126'}], 'gateway_ip': '47.182.199.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.182.199.66', 'tags': [], 'project_id': 'fd2b749b642446469baf31a6d5a3c529'}}
2025-09-15 03:00:58,038 - INFO - Created VN ctest-right_vn-71126388
2025-09-15 03:00:58,048 - DEBUG - VN ctest-right_vn-71126388 UUID is 9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,115 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:58,121 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:58,132 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:58,229 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,241 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,249 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:00:58,257 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:00:58,263 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/34ec2b53-fa7c-4cc1-82a4-2af1ae67bb9b
2025-09-15 03:00:58,270 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:58,270 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,279 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:00:58,294 - INFO - Verified VN network id 13 for VN 9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,294 - INFO - Verifications in API Server for VN ctest-right_vn-71126388 passed
2025-09-15 03:00:58,294 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,303 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:00:58,310 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:00:58,317 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/34ec2b53-fa7c-4cc1-82a4-2af1ae67bb9b
2025-09-15 03:00:58,327 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11241630044402174454', 'uuid-lslong': '12884869651571927041'}, 'enable': 'true', 'created': '2025-09-15T03:00:57', 'last-modified': '2025-09-15T03:00:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.361082'}
2025-09-15 03:00:58,333 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:58,338 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11241630044402174454', 'uuid-lslong': '12884869651571927041'}, 'enable': 'true', 'created': '2025-09-15T03:00:57', 'last-modified': '2025-09-15T03:00:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370087'}
2025-09-15 03:00:58,344 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:58,349 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-40834452', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11241630044402174454', 'uuid-lslong': '12884869651571927041'}, 'enable': 'true', 'created': '2025-09-15T03:00:57', 'last-modified': '2025-09-15T03:00:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380998'}
2025-09-15 03:00:58,354 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-15 03:00:58,354 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-71126388 passed
2025-09-15 03:00:58,354 - DEBUG - ====Verifying policy data for ctest-right_vn-71126388 in API_Server ======
2025-09-15 03:00:58,354 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:00:58,360 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:00:58,370 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:00:58,461 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/9c024af6-f4f3-49f6-b2d0-40ae5f27e801
2025-09-15 03:00:58,476 - DEBUG - =>VN ctest-right_vn-71126388 has no policy to be verified
2025-09-15 03:00:58,476 - DEBUG - Verifying the vn in opserver
2025-09-15 03:00:58,476 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388 virtual network link through opserver 10.0.0.249
2025-09-15 03:00:58,476 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-09-15 03:00:58,493 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388?flat'}
2025-09-15 03:00:58,493 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388 virtual network link through opserver 10.0.0.27
2025-09-15 03:00:58,493 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-15 03:00:58,505 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388?flat'}
2025-09-15 03:00:58,505 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388 virtual network link through opserver 10.0.0.38
2025-09-15 03:00:58,505 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-15 03:00:58,517 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388?flat'}
2025-09-15 03:00:58,517 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-40834452:ctest-right_vn-71126388 is found in opserver
2025-09-15 03:00:58,537 - DEBUG - Do not have enough data to verify VN in agent
2025-09-15 03:00:58,546 - DEBUG - VRF ids for VN ctest-right_vn-71126388: {}
2025-09-15 03:00:59,629 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2)
2025-09-15 03:01:00,893 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1)
2025-09-15 03:01:00,894 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-18390608']
2025-09-15 03:01:01,228 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-18390608'] created succesfully
2025-09-15 03:01:01,591 - DEBUG - Image by name tiny_nat_fw not found
2025-09-15 03:01:01,592 - DEBUG - Installing image tiny_nat_fw
2025-09-15 03:01:01,592 - 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'"}
2025-09-15 03:01:01,592 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:01:01,592 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:01:01,592 - DEBUG - Unzip image
2025-09-15 03:01:01,592 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-15 03:01:01,615 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-15 03:01:01,616 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-18390608']
2025-09-15 03:01:01,711 - INFO - Service template: ['default-domain', 'ctest-service_template_1-18390608'] deleted successfully.
2025-09-15 03:01:01,712 - INFO - Skip interface_detach for VM ctest-right_vm-69270957 in state BUILD
2025-09-15 03:01:01,712 - INFO - Deleting VM ctest-right_vm-69270957
2025-09-15 03:01:01,712 - ERROR - VM ctest-right_vm-69270957 has failed to come up
2025-09-15 03:01:01,712 - ERROR - Nova failed to get host of the VM
2025-09-15 03:01:01,713 - ERROR - VM ctest-right_vm-69270957 has failed to come up
2025-09-15 03:01:01,713 - ERROR - Nova failed to get host of the VM
2025-09-15 03:01:01,879 - INFO - Skip interface_detach for VM ctest-left_vm-20352535 in state BUILD
2025-09-15 03:01:01,879 - INFO - Deleting VM ctest-left_vm-20352535
2025-09-15 03:01:01,879 - ERROR - VM ctest-left_vm-20352535 has failed to come up
2025-09-15 03:01:01,879 - ERROR - Nova failed to get host of the VM
2025-09-15 03:01:01,880 - ERROR - VM ctest-left_vm-20352535 has failed to come up
2025-09-15 03:01:01,880 - ERROR - Nova failed to get host of the VM
2025-09-15 03:01:01,975 - INFO - Deleting VN ctest-right_vn-71126388
2025-09-15 03:01:02,040 - DEBUG - VN 9c024af6-f4f3-49f6-b2d0-40ae5f27e801 still in use: Unable to complete operation on network 9c024af6-f4f3-49f6-b2d0-40ae5f27e801. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0876cbc1-7a7c-46e3-b418-2483a4a1242c']
2025-09-15 03:01:02,040 - WARNING - Deleting VN ctest-right_vn-71126388 failed..Will retry
2025-09-15 03:01:04,230 - DEBUG - Response for deleting network ()
2025-09-15 03:01:04,230 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/490b6ff4-c66a-4999-b8a8-352fd5e48828
2025-09-15 03:01:04,236 - DEBUG - Response Code: 404
2025-09-15 03:01:04,236 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:01:04,247 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:01:04,312 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:01:04,405 - INFO - Validated that VN ctest-right_vn-71126388 is not found in API Server
2025-09-15 03:01:04,418 - DEBUG - VN ctest-right_vn-71126388 is not present in Agent 10.0.0.19
2025-09-15 03:01:04,428 - DEBUG - VN ctest-right_vn-71126388 is not present in Agent 10.0.0.137
2025-09-15 03:01:04,428 - INFO - Validated that VN ctest-right_vn-71126388 is not in any agent
2025-09-15 03:01:04,455 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-71126388 info
2025-09-15 03:01:04,455 - INFO - Deleting VN ctest-left_vn-47971134
2025-09-15 03:01:04,628 - DEBUG - Response for deleting network ()
2025-09-15 03:01:04,628 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/65356e4f-8d4b-4b9e-87e0-63e138302744
2025-09-15 03:01:04,634 - DEBUG - Response Code: 404
2025-09-15 03:01:04,634 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:01:04,645 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:01:04,702 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:01:04,801 - INFO - Validated that VN ctest-left_vn-47971134 is not found in API Server
2025-09-15 03:01:04,811 - DEBUG - VN ctest-left_vn-47971134 is not present in Agent 10.0.0.19
2025-09-15 03:01:04,823 - DEBUG - VN ctest-left_vn-47971134 is not present in Agent 10.0.0.137
2025-09-15 03:01:04,823 - INFO - Validated that VN ctest-left_vn-47971134 is not in any agent
2025-09-15 03:01:04,856 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-47971134 info
2025-09-15 03:01:04,856 - INFO - Deleting VN ctest-mgmt_vn-96996452
2025-09-15 03:01:04,996 - DEBUG - Response for deleting network ()
2025-09-15 03:01:04,996 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/32eadc87-e7a6-47dd-8f57-600a07b79085
2025-09-15 03:01:05,002 - DEBUG - Response Code: 404
2025-09-15 03:01:05,002 - DEBUG - Requesting: http://10.0.0.249:8082/domains
2025-09-15 03:01:05,008 - DEBUG - Requesting: http://10.0.0.249:8082/domain/fb35da95-2d14-4bd5-8610-16128fba9774
2025-09-15 03:01:05,027 - DEBUG - Requesting: http://10.0.0.249:8082/project/fd2b749b-6424-4646-9baf-31a6d5a3c529
2025-09-15 03:01:05,132 - INFO - Validated that VN ctest-mgmt_vn-96996452 is not found in API Server
2025-09-15 03:01:05,151 - DEBUG - VN ctest-mgmt_vn-96996452 is not present in Agent 10.0.0.19
2025-09-15 03:01:05,165 - DEBUG - VN ctest-mgmt_vn-96996452 is not present in Agent 10.0.0.137
2025-09-15 03:01:05,165 - INFO - Validated that VN ctest-mgmt_vn-96996452 is not in any agent
2025-09-15 03:01:05,209 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-96996452 info
2025-09-15 03:01:06,479 - DEBUG - No XMPP flaps were noticed during the test
2025-09-15 03:01:06,479 - INFO -
2025-09-15 03:01:06,479 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-09-15 03:01:06,479 - INFO - --------------------------------------------------------------------------------
2025-09-15 03:01:07,181 - INFO - Deleted project: ctest-TestSvcRegr-40834452, ID : fd2b749b-6424-4646-9baf-31a6d5a3c529