]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1)
2025-08-06 08:41:58,791 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-44198427']
2025-08-06 08:41:59,118 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-44198427'] created succesfully
2025-08-06 08:41:59,580 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 08:41:59,581 - DEBUG - Installing image tiny_nat_fw
2025-08-06 08:41:59,581 - 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-08-06 08:41:59,581 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-06 08:41:59,581 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-06 08:41:59,604 - 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-08-06 08:41:59,689 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:41:59,690 - DEBUG - Unzip image
2025-08-06 08:41:59,690 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:41:59,720 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 08:41:59,721 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-44198427']
2025-08-06 08:41:59,826 - INFO - Service template: ['default-domain', 'ctest-service_template_1-44198427'] deleted successfully.
2025-08-06 08:41:59,826 - INFO - Skip interface_detach for VM ctest-right_vm-71448625 in state BUILD
2025-08-06 08:41:59,827 - INFO - Deleting VM ctest-right_vm-71448625
2025-08-06 08:41:59,859 - ERROR - VM ctest-right_vm-71448625 has failed to come up
2025-08-06 08:41:59,859 - ERROR - Nova failed to get host of the VM
2025-08-06 08:41:59,859 - ERROR - VM ctest-right_vm-71448625 has failed to come up
2025-08-06 08:41:59,859 - ERROR - Nova failed to get host of the VM
2025-08-06 08:41:59,957 - INFO - Skip interface_detach for VM ctest-left_vm-00375725 in state BUILD
2025-08-06 08:41:59,957 - INFO - Deleting VM ctest-left_vm-00375725
2025-08-06 08:41:59,957 - ERROR - VM ctest-left_vm-00375725 has failed to come up
2025-08-06 08:41:59,957 - ERROR - Nova failed to get host of the VM
2025-08-06 08:41:59,957 - ERROR - VM ctest-left_vm-00375725 has failed to come up
2025-08-06 08:41:59,958 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:00,037 - INFO - Deleting VN ctest-right_vn-15408620
2025-08-06 08:42:00,087 - DEBUG - VN e400f172-f60f-4dd5-8e92-7e0627f6d30e still in use: Unable to complete operation on network e400f172-f60f-4dd5-8e92-7e0627f6d30e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ddeca1fc-794a-4f76-9d52-3799dcd8872a']
2025-08-06 08:42:00,087 - WARNING - Deleting VN ctest-right_vn-15408620 failed..Will retry
2025-08-06 08:42:02,377 - DEBUG - Response for deleting network ()
2025-08-06 08:42:02,377 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/c04f8961-962f-42d7-9821-5056fa6e7113
2025-08-06 08:42:02,390 - DEBUG - Response Code: 404
2025-08-06 08:42:02,390 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:02,399 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:02,420 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:02,528 - INFO - Validated that VN ctest-right_vn-15408620 is not found in API Server
2025-08-06 08:42:02,546 - DEBUG - VN ctest-right_vn-15408620 is not present in Agent 10.0.0.49
2025-08-06 08:42:02,563 - DEBUG - VN ctest-right_vn-15408620 is not present in Agent 10.0.0.57
2025-08-06 08:42:02,563 - INFO - Validated that VN ctest-right_vn-15408620 is not in any agent
2025-08-06 08:42:02,615 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-15408620 info
2025-08-06 08:42:02,615 - INFO - Deleting VN ctest-left_vn-50157987
2025-08-06 08:42:02,847 - DEBUG - Response for deleting network ()
2025-08-06 08:42:02,847 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2841a5b1-b50a-442b-89d7-0b33c6f03073
2025-08-06 08:42:02,859 - DEBUG - Response Code: 404
2025-08-06 08:42:02,859 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:02,878 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:02,915 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:03,034 - INFO - Validated that VN ctest-left_vn-50157987 is not found in API Server
2025-08-06 08:42:03,047 - DEBUG - VN ctest-left_vn-50157987 is not present in Agent 10.0.0.49
2025-08-06 08:42:03,066 - DEBUG - VN ctest-left_vn-50157987 is not present in Agent 10.0.0.57
2025-08-06 08:42:03,066 - INFO - Validated that VN ctest-left_vn-50157987 is not in any agent
2025-08-06 08:42:03,112 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-50157987 info
2025-08-06 08:42:03,112 - INFO - Deleting VN ctest-mgmt_vn-98567537
2025-08-06 08:42:03,315 - DEBUG - Response for deleting network ()
2025-08-06 08:42:03,315 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/f40b5f2c-7654-4a00-b906-02de225671e5
2025-08-06 08:42:03,322 - DEBUG - Response Code: 404
2025-08-06 08:42:03,322 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:03,330 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:03,363 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:03,469 - INFO - Validated that VN ctest-mgmt_vn-98567537 is not found in API Server
2025-08-06 08:42:03,492 - DEBUG - VN ctest-mgmt_vn-98567537 is not present in Agent 10.0.0.49
2025-08-06 08:42:03,510 - DEBUG - VN ctest-mgmt_vn-98567537 is not present in Agent 10.0.0.57
2025-08-06 08:42:03,510 - INFO - Validated that VN ctest-mgmt_vn-98567537 is not in any agent
2025-08-06 08:42:03,558 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-98567537 info
2025-08-06 08:42:03,558 - INFO - Deleting VM ctest-vm-in-third-vn-78393239
2025-08-06 08:42:03,640 - INFO - Deleting VN ctest-third-vn-30207328
2025-08-06 08:42:03,702 - DEBUG - VN d2dcd636-e3a6-4725-9afd-6f157ebbff0e still in use: Unable to complete operation on network d2dcd636-e3a6-4725-9afd-6f157ebbff0e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-63c34d66-8445-4fd2-aca3-86bbfdf2c8a7']
2025-08-06 08:42:03,702 - WARNING - Deleting VN ctest-third-vn-30207328 failed..Will retry
2025-08-06 08:42:05,897 - DEBUG - Response for deleting network ()
2025-08-06 08:42:07,204 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 08:42:07,204 - INFO -
2025-08-06 08:42:07,204 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:28]
2025-08-06 08:42:07,204 - INFO - --------------------------------------------------------------------------------
2025-08-06 08:42:07,208 - INFO - ================================================================================
2025-08-06 08:42:07,208 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-06 08:42:08,486 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-08-06 08:42:08,486 - INFO - Initial checks done. Running the testcase now
2025-08-06 08:42:08,486 - INFO -
2025-08-06 08:42:08,813 - DEBUG - Response for create_network : {'network': {'id': '5f0813cf-1f8b-43dc-bb7b-e7af104eb682', 'name': 'ctest-mgmt_vn-32106217', 'tenant_id': '00c517117003411398dea4467b4162ae', 'project_id': '00c517117003411398dea4467b4162ae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-33308751', 'ctest-mgmt_vn-32106217'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:09,124 - DEBUG - Response for create_subnet : {'subnet': {'id': 'acf72ee2-3e8e-45ab-8cf1-79c6640d8fde', 'name': '', 'tenant_id': '00c517117003411398dea4467b4162ae', 'network_id': '5f0813cf-1f8b-43dc-bb7b-e7af104eb682', 'ip_version': 4, 'cidr': '215.92.63.192/26', 'allocation_pools': [{'start': '215.92.63.194', 'end': '215.92.63.254'}], 'gateway_ip': '215.92.63.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '215.92.63.194', 'tags': [], 'project_id': '00c517117003411398dea4467b4162ae'}}
2025-08-06 08:42:09,152 - INFO - Created VN ctest-mgmt_vn-32106217
2025-08-06 08:42:09,169 - DEBUG - VN ctest-mgmt_vn-32106217 UUID is 5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,263 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:09,273 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:09,293 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:09,419 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,440 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,457 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:09,471 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:09,485 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/24f4481c-a4e0-48a0-b4bb-5b8906ea2006
2025-08-06 08:42:09,503 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:09,503 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,517 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:09,539 - INFO - Verified VN network id 15 for VN 5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,539 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32106217 passed
2025-08-06 08:42:09,539 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,553 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:09,565 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:09,576 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/24f4481c-a4e0-48a0-b4bb-5b8906ea2006
2025-08-06 08:42:09,596 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6847745013725217756', 'uuid-lslong': '13509646246306821762'}, 'enable': 'true', 'created': '2025-08-06T08:42:08', 'last-modified': '2025-08-06T08:42:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.552572'}
2025-08-06 08:42:09,604 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:09,612 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6847745013725217756', 'uuid-lslong': '13509646246306821762'}, 'enable': 'true', 'created': '2025-08-06T08:42:08', 'last-modified': '2025-08-06T08:42:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.574920'}
2025-08-06 08:42:09,621 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:09,628 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6847745013725217756', 'uuid-lslong': '13509646246306821762'}, 'enable': 'true', 'created': '2025-08-06T08:42:08', 'last-modified': '2025-08-06T08:42:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.589613'}
2025-08-06 08:42:09,637 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:09,637 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32106217 passed
2025-08-06 08:42:09,638 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32106217 in API_Server ======
2025-08-06 08:42:09,638 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:09,649 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:09,672 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:09,784 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/5f0813cf-1f8b-43dc-bb7b-e7af104eb682
2025-08-06 08:42:09,796 - DEBUG - =>VN ctest-mgmt_vn-32106217 has no policy to be verified
2025-08-06 08:42:09,797 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:09,797 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:09,797 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:09,812 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217?flat'}
2025-08-06 08:42:09,812 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:09,812 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:09,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217?flat'}
2025-08-06 08:42:09,829 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:09,830 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:09,845 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217?flat'}
2025-08-06 08:42:09,845 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-33308751:ctest-mgmt_vn-32106217 is found in opserver
2025-08-06 08:42:09,879 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:09,899 - DEBUG - VRF ids for VN ctest-mgmt_vn-32106217: {}
2025-08-06 08:42:10,209 - DEBUG - Response for create_network : {'network': {'id': '4cc220c5-9310-4216-beba-a79ca7cf184b', 'name': 'ctest-left_vn-63198263', 'tenant_id': '00c517117003411398dea4467b4162ae', 'project_id': '00c517117003411398dea4467b4162ae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-33308751', 'ctest-left_vn-63198263'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:10,543 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b1b4aea2-e447-409d-93bb-33b04ff777f3', 'name': '', 'tenant_id': '00c517117003411398dea4467b4162ae', 'network_id': '4cc220c5-9310-4216-beba-a79ca7cf184b', 'ip_version': 4, 'cidr': '148.77.138.0/26', 'allocation_pools': [{'start': '148.77.138.2', 'end': '148.77.138.62'}], 'gateway_ip': '148.77.138.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '148.77.138.2', 'tags': [], 'project_id': '00c517117003411398dea4467b4162ae'}}
2025-08-06 08:42:10,570 - INFO - Created VN ctest-left_vn-63198263
2025-08-06 08:42:10,583 - DEBUG - VN ctest-left_vn-63198263 UUID is 4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,679 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:10,688 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:10,704 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:10,818 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,836 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,849 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:10,861 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:10,874 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f379d839-b7d8-410b-89ce-967cdd64f58c
2025-08-06 08:42:10,889 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:10,889 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,902 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:10,921 - INFO - Verified VN network id 16 for VN 4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,921 - INFO - Verifications in API Server for VN ctest-left_vn-63198263 passed
2025-08-06 08:42:10,921 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:10,936 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:10,947 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:10,955 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f379d839-b7d8-410b-89ce-967cdd64f58c
2025-08-06 08:42:10,975 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531019325312352790', 'uuid-lslong': '13743481504146593867'}, 'enable': 'true', 'created': '2025-08-06T08:42:10', 'last-modified': '2025-08-06T08:42:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500114'}
2025-08-06 08:42:10,984 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:10,991 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531019325312352790', 'uuid-lslong': '13743481504146593867'}, 'enable': 'true', 'created': '2025-08-06T08:42:10', 'last-modified': '2025-08-06T08:42:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520543'}
2025-08-06 08:42:10,999 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:11,007 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531019325312352790', 'uuid-lslong': '13743481504146593867'}, 'enable': 'true', 'created': '2025-08-06T08:42:10', 'last-modified': '2025-08-06T08:42:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532815'}
2025-08-06 08:42:11,015 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:11,015 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-63198263 passed
2025-08-06 08:42:11,015 - DEBUG - ====Verifying policy data for ctest-left_vn-63198263 in API_Server ======
2025-08-06 08:42:11,016 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:11,023 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:11,040 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:11,151 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/4cc220c5-9310-4216-beba-a79ca7cf184b
2025-08-06 08:42:11,174 - DEBUG - =>VN ctest-left_vn-63198263 has no policy to be verified
2025-08-06 08:42:11,174 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:11,175 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:11,175 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:11,198 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263?flat'}
2025-08-06 08:42:11,198 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:11,198 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:11,223 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263?flat'}
2025-08-06 08:42:11,223 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:11,223 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:11,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263?flat'}
2025-08-06 08:42:11,240 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-33308751:ctest-left_vn-63198263 is found in opserver
2025-08-06 08:42:11,268 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:11,286 - DEBUG - VRF ids for VN ctest-left_vn-63198263: {}
2025-08-06 08:42:11,647 - DEBUG - Response for create_network : {'network': {'id': '6919f4a4-c37d-45f8-9163-5dda81f4a2b6', 'name': 'ctest-right_vn-30650323', 'tenant_id': '00c517117003411398dea4467b4162ae', 'project_id': '00c517117003411398dea4467b4162ae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-33308751', 'ctest-right_vn-30650323'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:11,926 - DEBUG - Response for create_subnet : {'subnet': {'id': '478767be-caa2-400b-9d51-6ab19582f203', 'name': '', 'tenant_id': '00c517117003411398dea4467b4162ae', 'network_id': '6919f4a4-c37d-45f8-9163-5dda81f4a2b6', 'ip_version': 4, 'cidr': '138.17.5.192/26', 'allocation_pools': [{'start': '138.17.5.194', 'end': '138.17.5.254'}], 'gateway_ip': '138.17.5.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.17.5.194', 'tags': [], 'project_id': '00c517117003411398dea4467b4162ae'}}
2025-08-06 08:42:11,952 - INFO - Created VN ctest-right_vn-30650323
2025-08-06 08:42:11,965 - DEBUG - VN ctest-right_vn-30650323 UUID is 6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,066 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:12,076 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:12,097 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:12,204 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,221 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,233 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:12,246 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:12,260 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/c80c5db1-0ef7-4b40-a650-fe42cce687b0
2025-08-06 08:42:12,272 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:12,272 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,290 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:12,311 - INFO - Verified VN network id 17 for VN 6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,311 - INFO - Verifications in API Server for VN ctest-right_vn-30650323 passed
2025-08-06 08:42:12,311 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,329 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:12,342 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:12,357 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/c80c5db1-0ef7-4b40-a650-fe42cce687b0
2025-08-06 08:42:12,377 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7573353236891780600', 'uuid-lslong': '10476320351258452662'}, 'enable': 'true', 'created': '2025-08-06T08:42:11', 'last-modified': '2025-08-06T08:42:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.513906'}
2025-08-06 08:42:12,386 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:12,394 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7573353236891780600', 'uuid-lslong': '10476320351258452662'}, 'enable': 'true', 'created': '2025-08-06T08:42:11', 'last-modified': '2025-08-06T08:42:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.528223'}
2025-08-06 08:42:12,402 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:12,410 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7573353236891780600', 'uuid-lslong': '10476320351258452662'}, 'enable': 'true', 'created': '2025-08-06T08:42:11', 'last-modified': '2025-08-06T08:42:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.536154'}
2025-08-06 08:42:12,420 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:12,420 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-30650323 passed
2025-08-06 08:42:12,421 - DEBUG - ====Verifying policy data for ctest-right_vn-30650323 in API_Server ======
2025-08-06 08:42:12,421 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:12,430 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:12,449 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:12,577 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/6919f4a4-c37d-45f8-9163-5dda81f4a2b6
2025-08-06 08:42:12,589 - DEBUG - =>VN ctest-right_vn-30650323 has no policy to be verified
2025-08-06 08:42:12,589 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:12,589 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:12,589 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:12,604 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323?flat'}
2025-08-06 08:42:12,605 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:12,605 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:12,622 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323?flat'}
2025-08-06 08:42:12,622 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:12,622 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:12,647 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323?flat'}
2025-08-06 08:42:12,647 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-33308751:ctest-right_vn-30650323 is found in opserver
2025-08-06 08:42:12,677 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:12,690 - DEBUG - VRF ids for VN ctest-right_vn-30650323: {}
2025-08-06 08:42:13,025 - DEBUG - Response for create_network : {'network': {'id': 'd9531db1-8afb-41ef-9be4-b11bfe596865', 'name': 'ctest-trans_left_vn-79778775', 'tenant_id': '00c517117003411398dea4467b4162ae', 'project_id': '00c517117003411398dea4467b4162ae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-33308751', 'ctest-trans_left_vn-79778775'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:13,328 - DEBUG - Response for create_subnet : {'subnet': {'id': 'acfe2a1d-75d5-4b9d-8c92-8217ef833dbb', 'name': '', 'tenant_id': '00c517117003411398dea4467b4162ae', 'network_id': 'd9531db1-8afb-41ef-9be4-b11bfe596865', 'ip_version': 4, 'cidr': '22.56.98.192/26', 'allocation_pools': [{'start': '22.56.98.194', 'end': '22.56.98.254'}], 'gateway_ip': '22.56.98.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22.56.98.194', 'tags': [], 'project_id': '00c517117003411398dea4467b4162ae'}}
2025-08-06 08:42:13,353 - INFO - Created VN ctest-trans_left_vn-79778775
2025-08-06 08:42:13,369 - DEBUG - VN ctest-trans_left_vn-79778775 UUID is d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,455 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:13,469 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:13,488 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:13,624 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,647 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,660 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:13,691 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:13,727 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f99ed84d-8c48-4cb2-b314-1f372276bc95
2025-08-06 08:42:13,736 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 08:42:13,736 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,751 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:13,776 - INFO - Verified VN network id 18 for VN d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,777 - INFO - Verifications in API Server for VN ctest-trans_left_vn-79778775 passed
2025-08-06 08:42:13,777 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:13,793 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:13,809 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:13,827 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/f99ed84d-8c48-4cb2-b314-1f372276bc95
2025-08-06 08:42:13,859 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659892977675485679', 'uuid-lslong': '11233298104358365285'}, 'enable': 'true', 'created': '2025-08-06T08:42:12', 'last-modified': '2025-08-06T08:42:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.624979'}
2025-08-06 08:42:13,884 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 08:42:13,897 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659892977675485679', 'uuid-lslong': '11233298104358365285'}, 'enable': 'true', 'created': '2025-08-06T08:42:12', 'last-modified': '2025-08-06T08:42:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.664942'}
2025-08-06 08:42:13,911 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 08:42:13,919 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15659892977675485679', 'uuid-lslong': '11233298104358365285'}, 'enable': 'true', 'created': '2025-08-06T08:42:12', 'last-modified': '2025-08-06T08:42:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.682256'}
2025-08-06 08:42:13,927 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 08:42:13,928 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-79778775 passed
2025-08-06 08:42:13,928 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-79778775 in API_Server ======
2025-08-06 08:42:13,928 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:13,939 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:13,956 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:14,058 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/d9531db1-8afb-41ef-9be4-b11bfe596865
2025-08-06 08:42:14,073 - DEBUG - =>VN ctest-trans_left_vn-79778775 has no policy to be verified
2025-08-06 08:42:14,073 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:14,073 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:14,073 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:14,088 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775?flat'}
2025-08-06 08:42:14,088 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:14,088 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:14,109 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775?flat'}
2025-08-06 08:42:14,109 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:14,109 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:14,127 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775?flat'}
2025-08-06 08:42:14,127 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-33308751:ctest-trans_left_vn-79778775 is found in opserver
2025-08-06 08:42:14,154 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:14,174 - DEBUG - VRF ids for VN ctest-trans_left_vn-79778775: {}
2025-08-06 08:42:14,583 - DEBUG - Response for create_network : {'network': {'id': '60839459-d655-4261-a25d-9368338ee9db', 'name': 'ctest-trans_right_vn-66681701', 'tenant_id': '00c517117003411398dea4467b4162ae', 'project_id': '00c517117003411398dea4467b4162ae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-33308751', 'ctest-trans_right_vn-66681701'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:14,883 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fd0de2b3-f90b-4ec0-aec7-e43e4d3cbe84', 'name': '', 'tenant_id': '00c517117003411398dea4467b4162ae', 'network_id': '60839459-d655-4261-a25d-9368338ee9db', 'ip_version': 4, 'cidr': '173.187.9.192/26', 'allocation_pools': [{'start': '173.187.9.194', 'end': '173.187.9.254'}], 'gateway_ip': '173.187.9.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.187.9.194', 'tags': [], 'project_id': '00c517117003411398dea4467b4162ae'}}
2025-08-06 08:42:14,909 - INFO - Created VN ctest-trans_right_vn-66681701
2025-08-06 08:42:14,921 - DEBUG - VN ctest-trans_right_vn-66681701 UUID is 60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,025 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:15,034 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:15,050 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:15,186 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,203 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,216 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:15,236 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:15,249 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/7a1e3312-1407-4568-8585-ba1bcea441a9
2025-08-06 08:42:15,260 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 08:42:15,260 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,275 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:15,297 - INFO - Verified VN network id 19 for VN 60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,298 - INFO - Verifications in API Server for VN ctest-trans_right_vn-66681701 passed
2025-08-06 08:42:15,306 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,320 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:15,331 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:15,340 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/7a1e3312-1407-4568-8585-ba1bcea441a9
2025-08-06 08:42:15,360 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6954565363159089761', 'uuid-lslong': '11699669482729302491'}, 'enable': 'true', 'created': '2025-08-06T08:42:14', 'last-modified': '2025-08-06T08:42:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.578529'}
2025-08-06 08:42:15,372 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 08:42:15,381 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6954565363159089761', 'uuid-lslong': '11699669482729302491'}, 'enable': 'true', 'created': '2025-08-06T08:42:14', 'last-modified': '2025-08-06T08:42:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.600694'}
2025-08-06 08:42:15,390 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 08:42:15,396 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-33308751', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6954565363159089761', 'uuid-lslong': '11699669482729302491'}, 'enable': 'true', 'created': '2025-08-06T08:42:14', 'last-modified': '2025-08-06T08:42:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.618000'}
2025-08-06 08:42:15,406 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 08:42:15,406 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-66681701 passed
2025-08-06 08:42:15,407 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-66681701 in API_Server ======
2025-08-06 08:42:15,407 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:15,415 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:15,430 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:15,549 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/60839459-d655-4261-a25d-9368338ee9db
2025-08-06 08:42:15,564 - DEBUG - =>VN ctest-trans_right_vn-66681701 has no policy to be verified
2025-08-06 08:42:15,564 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:15,564 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:15,564 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:15,580 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701?flat'}
2025-08-06 08:42:15,580 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:15,580 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:15,599 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701?flat'}
2025-08-06 08:42:15,599 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:15,599 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:15,619 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701?flat'}
2025-08-06 08:42:15,619 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-33308751:ctest-trans_right_vn-66681701 is found in opserver
2025-08-06 08:42:15,651 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:15,666 - DEBUG - VRF ids for VN ctest-trans_right_vn-66681701: {}
2025-08-06 08:42:17,001 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1)
2025-08-06 08:42:18,586 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2)
2025-08-06 08:42:18,587 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-69035730']
2025-08-06 08:42:18,910 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-69035730'] created succesfully
2025-08-06 08:42:19,395 - DEBUG - Image by name tiny_trans_fw not found
2025-08-06 08:42:19,395 - DEBUG - Installing image tiny_trans_fw
2025-08-06 08:42:19,395 - 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-08-06 08:42:19,395 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-06 08:42:19,395 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-06 08:42:19,414 - 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-08-06 08:42:19,474 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 08:42:19,474 - DEBUG - Unzip image
2025-08-06 08:42:19,474 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 08:42:19,519 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-06 08:42:19,520 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-69035730']
2025-08-06 08:42:19,630 - INFO - Service template: ['default-domain', 'ctest-service_template_1-69035730'] deleted successfully.
2025-08-06 08:42:19,630 - INFO - Skip interface_detach for VM ctest-right_vm-58132051 in state BUILD
2025-08-06 08:42:19,631 - INFO - Deleting VM ctest-right_vm-58132051
2025-08-06 08:42:19,631 - ERROR - VM ctest-right_vm-58132051 has failed to come up
2025-08-06 08:42:19,631 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:19,631 - ERROR - VM ctest-right_vm-58132051 has failed to come up
2025-08-06 08:42:19,631 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:19,717 - INFO - Skip interface_detach for VM ctest-left_vm-49486339 in state BUILD
2025-08-06 08:42:19,717 - INFO - Deleting VM ctest-left_vm-49486339
2025-08-06 08:42:19,717 - ERROR - VM ctest-left_vm-49486339 has failed to come up
2025-08-06 08:42:19,717 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:19,717 - ERROR - VM ctest-left_vm-49486339 has failed to come up
2025-08-06 08:42:19,717 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:19,822 - INFO - Deleting VN ctest-trans_right_vn-66681701
2025-08-06 08:42:20,002 - DEBUG - Response for deleting network ()
2025-08-06 08:42:20,002 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/511d3c1b-f220-4ff4-b6d4-0d045aee0895
2025-08-06 08:42:20,016 - DEBUG - Response Code: 404
2025-08-06 08:42:20,016 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:20,026 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:20,056 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:20,177 - INFO - Validated that VN ctest-trans_right_vn-66681701 is not found in API Server
2025-08-06 08:42:20,196 - DEBUG - VN ctest-trans_right_vn-66681701 is not present in Agent 10.0.0.49
2025-08-06 08:42:20,219 - DEBUG - VN ctest-trans_right_vn-66681701 is not present in Agent 10.0.0.57
2025-08-06 08:42:20,220 - INFO - Validated that VN ctest-trans_right_vn-66681701 is not in any agent
2025-08-06 08:42:20,274 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-66681701 info
2025-08-06 08:42:20,275 - INFO - Deleting VN ctest-trans_left_vn-79778775
2025-08-06 08:42:20,524 - DEBUG - Response for deleting network ()
2025-08-06 08:42:20,524 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/98f3fb17-1d38-4275-aacb-758be04bf6ae
2025-08-06 08:42:20,531 - DEBUG - Response Code: 404
2025-08-06 08:42:20,532 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:20,539 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:20,566 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:20,661 - INFO - Validated that VN ctest-trans_left_vn-79778775 is not found in API Server
2025-08-06 08:42:20,679 - DEBUG - VN ctest-trans_left_vn-79778775 is not present in Agent 10.0.0.49
2025-08-06 08:42:20,727 - DEBUG - VN ctest-trans_left_vn-79778775 is not present in Agent 10.0.0.57
2025-08-06 08:42:20,727 - INFO - Validated that VN ctest-trans_left_vn-79778775 is not in any agent
2025-08-06 08:42:20,799 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-79778775 info
2025-08-06 08:42:20,799 - INFO - Deleting VN ctest-right_vn-30650323
2025-08-06 08:42:20,853 - DEBUG - VN 6919f4a4-c37d-45f8-9163-5dda81f4a2b6 still in use: Unable to complete operation on network 6919f4a4-c37d-45f8-9163-5dda81f4a2b6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1c717a3a-d29f-41ac-aab8-7a6e7c3d387a']
2025-08-06 08:42:20,853 - WARNING - Deleting VN ctest-right_vn-30650323 failed..Will retry
2025-08-06 08:42:23,041 - DEBUG - Response for deleting network ()
2025-08-06 08:42:23,041 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/7d2f3470-3df5-4deb-a86f-677aa081852f
2025-08-06 08:42:23,063 - DEBUG - Response Code: 404
2025-08-06 08:42:23,063 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:23,082 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:23,100 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:23,196 - INFO - Validated that VN ctest-right_vn-30650323 is not found in API Server
2025-08-06 08:42:23,210 - DEBUG - VN ctest-right_vn-30650323 is not present in Agent 10.0.0.49
2025-08-06 08:42:23,227 - DEBUG - VN ctest-right_vn-30650323 is not present in Agent 10.0.0.57
2025-08-06 08:42:23,227 - INFO - Validated that VN ctest-right_vn-30650323 is not in any agent
2025-08-06 08:42:23,272 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-30650323 info
2025-08-06 08:42:23,272 - INFO - Deleting VN ctest-left_vn-63198263
2025-08-06 08:42:23,518 - DEBUG - Response for deleting network ()
2025-08-06 08:42:23,518 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/4b046c22-b8bd-4696-be64-beed687abf37
2025-08-06 08:42:23,525 - DEBUG - Response Code: 404
2025-08-06 08:42:23,525 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:23,535 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:23,577 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:23,686 - INFO - Validated that VN ctest-left_vn-63198263 is not found in API Server
2025-08-06 08:42:23,703 - DEBUG - VN ctest-left_vn-63198263 is not present in Agent 10.0.0.49
2025-08-06 08:42:23,716 - DEBUG - VN ctest-left_vn-63198263 is not present in Agent 10.0.0.57
2025-08-06 08:42:23,716 - INFO - Validated that VN ctest-left_vn-63198263 is not in any agent
2025-08-06 08:42:23,756 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-63198263 info
2025-08-06 08:42:23,756 - INFO - Deleting VN ctest-mgmt_vn-32106217
2025-08-06 08:42:23,959 - DEBUG - Response for deleting network ()
2025-08-06 08:42:23,960 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1303020d-3d11-4109-9872-926f9677942d
2025-08-06 08:42:23,973 - DEBUG - Response Code: 404
2025-08-06 08:42:23,973 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:23,985 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:24,021 - DEBUG - Requesting: http://10.0.0.48:8082/project/00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:24,148 - INFO - Validated that VN ctest-mgmt_vn-32106217 is not found in API Server
2025-08-06 08:42:24,165 - DEBUG - VN ctest-mgmt_vn-32106217 is not present in Agent 10.0.0.49
2025-08-06 08:42:24,187 - DEBUG - VN ctest-mgmt_vn-32106217 is not present in Agent 10.0.0.57
2025-08-06 08:42:24,187 - INFO - Validated that VN ctest-mgmt_vn-32106217 is not in any agent
2025-08-06 08:42:24,234 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-32106217 info
2025-08-06 08:42:25,542 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 08:42:25,542 - INFO -
2025-08-06 08:42:25,542 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2025-08-06 08:42:25,542 - INFO - --------------------------------------------------------------------------------
2025-08-06 08:42:26,281 - INFO - Deleted project: ctest-TestSvcRegr-33308751, ID : 00c51711-7003-4113-98de-a4467b4162ae
2025-08-06 08:42:26,475 - INFO - Domain Default found not creating
2025-08-06 08:42:26,651 - INFO - Project ctest-TestSvcRegr-87351752 not found, creating it
2025-08-06 08:42:27,237 - INFO - Created Project:ctest-TestSvcRegr-87351752, ID : c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:28,776 - INFO - ================================================================================
2025-08-06 08:42:28,776 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-06 08:42:30,065 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-08-06 08:42:30,066 - INFO - Initial checks done. Running the testcase now
2025-08-06 08:42:30,066 - INFO -
2025-08-06 08:42:30,754 - DEBUG - Response for create_network : {'network': {'id': 'c3af6d9d-1337-4361-a35e-5b763c553311', 'name': 'ctest-mgmt_vn-60651251', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'ctest-mgmt_vn-60651251'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:30,980 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b183251d-cd6f-4231-84cd-e977cf5fbae0', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': 'c3af6d9d-1337-4361-a35e-5b763c553311', 'ip_version': 4, 'cidr': '195.174.29.0/26', 'allocation_pools': [{'start': '195.174.29.2', 'end': '195.174.29.62'}], 'gateway_ip': '195.174.29.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '195.174.29.2', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:31,009 - INFO - Created VN ctest-mgmt_vn-60651251
2025-08-06 08:42:31,027 - DEBUG - VN ctest-mgmt_vn-60651251 UUID is c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,118 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:31,459 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:31,505 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:31,527 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:31,646 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,665 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,685 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:31,697 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:31,712 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/e764958e-5727-4f2f-9578-82b2e885c853
2025-08-06 08:42:31,723 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:31,723 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,741 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:31,764 - INFO - Verified VN network id 15 for VN c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,764 - INFO - Verifications in API Server for VN ctest-mgmt_vn-60651251 passed
2025-08-06 08:42:31,764 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,778 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:31,788 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:31,797 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/e764958e-5727-4f2f-9578-82b2e885c853
2025-08-06 08:42:31,813 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14100609479719994209', 'uuid-lslong': '11771947039369540369'}, 'enable': 'true', 'created': '2025-08-06T08:42:30', 'last-modified': '2025-08-06T08:42:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.893344'}
2025-08-06 08:42:31,821 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:31,828 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14100609479719994209', 'uuid-lslong': '11771947039369540369'}, 'enable': 'true', 'created': '2025-08-06T08:42:30', 'last-modified': '2025-08-06T08:42:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.910335'}
2025-08-06 08:42:31,837 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:31,844 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14100609479719994209', 'uuid-lslong': '11771947039369540369'}, 'enable': 'true', 'created': '2025-08-06T08:42:30', 'last-modified': '2025-08-06T08:42:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.921887'}
2025-08-06 08:42:31,851 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:31,852 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-60651251 passed
2025-08-06 08:42:31,852 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-60651251 in API_Server ======
2025-08-06 08:42:31,852 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:31,860 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:31,878 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:31,983 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/c3af6d9d-1337-4361-a35e-5b763c553311
2025-08-06 08:42:31,995 - DEBUG - =>VN ctest-mgmt_vn-60651251 has no policy to be verified
2025-08-06 08:42:31,995 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:31,995 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:31,995 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:32,292 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:32,367 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251?flat'}
2025-08-06 08:42:32,367 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:32,367 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:32,661 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:32,737 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251?flat'}
2025-08-06 08:42:32,737 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:32,737 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:33,038 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:33,109 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251?flat'}
2025-08-06 08:42:33,109 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-60651251 is found in opserver
2025-08-06 08:42:33,138 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:33,151 - DEBUG - VRF ids for VN ctest-mgmt_vn-60651251: {}
2025-08-06 08:42:33,449 - DEBUG - Response for create_network : {'network': {'id': '580a3898-a97a-4208-9ba7-66770f3003d1', 'name': 'service-left-vn-49801224', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'service-left-vn-49801224'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:33,709 - DEBUG - Response for create_subnet : {'subnet': {'id': '8572670c-0512-4937-b6c7-9cd7afe80dd3', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': '580a3898-a97a-4208-9ba7-66770f3003d1', 'ip_version': 4, 'cidr': '169.74.116.64/26', 'allocation_pools': [{'start': '169.74.116.66', 'end': '169.74.116.126'}], 'gateway_ip': '169.74.116.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '169.74.116.66', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:33,740 - INFO - Created VN service-left-vn-49801224
2025-08-06 08:42:33,753 - DEBUG - VN service-left-vn-49801224 UUID is 580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:33,841 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:33,849 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:33,864 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:33,969 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:33,985 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:34,002 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:34,014 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:34,023 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/a1d69911-3c1c-48c3-8399-5966abaa344d
2025-08-06 08:42:34,035 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:34,035 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:34,047 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:34,067 - INFO - Verified VN network id 16 for VN 580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:34,067 - INFO - Verifications in API Server for VN service-left-vn-49801224 passed
2025-08-06 08:42:34,067 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:34,080 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:34,089 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:34,100 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/a1d69911-3c1c-48c3-8399-5966abaa344d
2025-08-06 08:42:34,117 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6343945253434311176', 'uuid-lslong': '11216046058531455953'}, 'enable': 'true', 'created': '2025-08-06T08:42:33', 'last-modified': '2025-08-06T08:42:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477638'}
2025-08-06 08:42:34,124 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:34,132 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6343945253434311176', 'uuid-lslong': '11216046058531455953'}, 'enable': 'true', 'created': '2025-08-06T08:42:33', 'last-modified': '2025-08-06T08:42:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492189'}
2025-08-06 08:42:34,143 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:34,151 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6343945253434311176', 'uuid-lslong': '11216046058531455953'}, 'enable': 'true', 'created': '2025-08-06T08:42:33', 'last-modified': '2025-08-06T08:42:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.508622'}
2025-08-06 08:42:34,160 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:34,160 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-49801224 passed
2025-08-06 08:42:34,161 - DEBUG - ====Verifying policy data for service-left-vn-49801224 in API_Server ======
2025-08-06 08:42:34,161 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:34,173 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:34,192 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:34,310 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/580a3898-a97a-4208-9ba7-66770f3003d1
2025-08-06 08:42:34,326 - DEBUG - =>VN service-left-vn-49801224 has no policy to be verified
2025-08-06 08:42:34,326 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:34,326 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:34,326 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:34,395 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224?flat'}
2025-08-06 08:42:34,395 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:34,395 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:34,467 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224?flat'}
2025-08-06 08:42:34,467 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:34,467 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:34,535 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224?flat'}
2025-08-06 08:42:34,535 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:service-left-vn-49801224 is found in opserver
2025-08-06 08:42:34,564 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:34,580 - DEBUG - VRF ids for VN service-left-vn-49801224: {}
2025-08-06 08:42:34,922 - DEBUG - Response for create_network : {'network': {'id': '1a30612b-c135-4d14-bb97-04973fc5f5fe', 'name': 'service-right-vn-98578531', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'service-right-vn-98578531'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:35,181 - DEBUG - Response for create_subnet : {'subnet': {'id': '797499cc-175d-4c63-8559-0ab0ae18abd9', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': '1a30612b-c135-4d14-bb97-04973fc5f5fe', 'ip_version': 4, 'cidr': '172.162.149.128/26', 'allocation_pools': [{'start': '172.162.149.130', 'end': '172.162.149.190'}], 'gateway_ip': '172.162.149.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '172.162.149.130', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:35,212 - INFO - Created VN service-right-vn-98578531
2025-08-06 08:42:35,226 - DEBUG - VN service-right-vn-98578531 UUID is 1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,321 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:35,329 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:35,349 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:35,471 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,494 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,506 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:35,520 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:35,530 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/84f72e11-6332-41a0-8bd4-9fb1bc67b0b3
2025-08-06 08:42:35,540 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:35,541 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,572 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:35,609 - INFO - Verified VN network id 17 for VN 1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,609 - INFO - Verifications in API Server for VN service-right-vn-98578531 passed
2025-08-06 08:42:35,609 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,622 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:35,637 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:35,650 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/84f72e11-6332-41a0-8bd4-9fb1bc67b0b3
2025-08-06 08:42:35,676 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1887115084421221652', 'uuid-lslong': '13517277854232344062'}, 'enable': 'true', 'created': '2025-08-06T08:42:34', 'last-modified': '2025-08-06T08:42:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.572204'}
2025-08-06 08:42:35,688 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:35,698 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1887115084421221652', 'uuid-lslong': '13517277854232344062'}, 'enable': 'true', 'created': '2025-08-06T08:42:34', 'last-modified': '2025-08-06T08:42:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.591058'}
2025-08-06 08:42:35,706 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:35,713 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1887115084421221652', 'uuid-lslong': '13517277854232344062'}, 'enable': 'true', 'created': '2025-08-06T08:42:34', 'last-modified': '2025-08-06T08:42:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.607916'}
2025-08-06 08:42:35,722 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:35,722 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-98578531 passed
2025-08-06 08:42:35,722 - DEBUG - ====Verifying policy data for service-right-vn-98578531 in API_Server ======
2025-08-06 08:42:35,722 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:35,730 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:35,745 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:35,871 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/1a30612b-c135-4d14-bb97-04973fc5f5fe
2025-08-06 08:42:35,886 - DEBUG - =>VN service-right-vn-98578531 has no policy to be verified
2025-08-06 08:42:35,886 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:35,886 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:35,886 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:35,960 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531?flat'}
2025-08-06 08:42:35,960 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:35,960 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:36,026 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531?flat'}
2025-08-06 08:42:36,026 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:36,026 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:36,095 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531?flat'}
2025-08-06 08:42:36,095 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:service-right-vn-98578531 is found in opserver
2025-08-06 08:42:36,127 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:36,143 - DEBUG - VRF ids for VN service-right-vn-98578531: {}
2025-08-06 08:42:36,279 - DEBUG - Services list from nova: [, , , ]
2025-08-06 08:42:37,485 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1)
2025-08-06 08:42:39,123 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2)
2025-08-06 08:42:39,124 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-69257524']
2025-08-06 08:42:39,456 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-69257524'] created succesfully
2025-08-06 08:42:40,028 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 08:42:40,028 - DEBUG - Installing image tiny_nat_fw
2025-08-06 08:42:40,029 - 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-08-06 08:42:40,030 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:40,030 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:40,030 - DEBUG - Unzip image
2025-08-06 08:42:40,030 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:40,124 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 08:42:40,125 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-69257524']
2025-08-06 08:42:40,230 - INFO - Service template: ['default-domain', 'ctest-service_template_1-69257524'] deleted successfully.
2025-08-06 08:42:40,231 - INFO - Skip interface_detach for VM ctest-right_vm-66132197 in state BUILD
2025-08-06 08:42:40,231 - INFO - Deleting VM ctest-right_vm-66132197
2025-08-06 08:42:40,263 - ERROR - VM ctest-right_vm-66132197 has failed to come up
2025-08-06 08:42:40,263 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:40,263 - ERROR - VM ctest-right_vm-66132197 has failed to come up
2025-08-06 08:42:40,263 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:40,365 - INFO - Skip interface_detach for VM ctest-left_vm-28518969 in state BUILD
2025-08-06 08:42:40,365 - INFO - Deleting VM ctest-left_vm-28518969
2025-08-06 08:42:40,365 - ERROR - VM ctest-left_vm-28518969 has failed to come up
2025-08-06 08:42:40,365 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:40,365 - ERROR - VM ctest-left_vm-28518969 has failed to come up
2025-08-06 08:42:40,365 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:40,469 - INFO - Deleting VN service-right-vn-98578531
2025-08-06 08:42:40,527 - DEBUG - VN 1a30612b-c135-4d14-bb97-04973fc5f5fe still in use: Unable to complete operation on network 1a30612b-c135-4d14-bb97-04973fc5f5fe. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ad21c78f-4963-4975-9407-f65d2faaf460']
2025-08-06 08:42:40,527 - WARNING - Deleting VN service-right-vn-98578531 failed..Will retry
2025-08-06 08:42:42,735 - DEBUG - Response for deleting network ()
2025-08-06 08:42:42,735 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/8cb8ea32-da90-494c-b579-c97b596b0ec0
2025-08-06 08:42:42,745 - DEBUG - Response Code: 404
2025-08-06 08:42:42,746 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:42,758 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:42,777 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:42,905 - INFO - Validated that VN service-right-vn-98578531 is not found in API Server
2025-08-06 08:42:42,920 - DEBUG - VN service-right-vn-98578531 is not present in Agent 10.0.0.49
2025-08-06 08:42:42,939 - DEBUG - VN service-right-vn-98578531 is not present in Agent 10.0.0.57
2025-08-06 08:42:42,939 - INFO - Validated that VN service-right-vn-98578531 is not in any agent
2025-08-06 08:42:42,989 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-98578531 info
2025-08-06 08:42:42,989 - INFO - Deleting VN service-left-vn-49801224
2025-08-06 08:42:43,235 - DEBUG - Response for deleting network ()
2025-08-06 08:42:43,235 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/ced84cae-bc81-4994-9f4b-d8182c548930
2025-08-06 08:42:43,245 - DEBUG - Response Code: 404
2025-08-06 08:42:43,246 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:43,256 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:43,283 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:43,396 - INFO - Validated that VN service-left-vn-49801224 is not found in API Server
2025-08-06 08:42:43,416 - DEBUG - VN service-left-vn-49801224 is not present in Agent 10.0.0.49
2025-08-06 08:42:43,437 - DEBUG - VN service-left-vn-49801224 is not present in Agent 10.0.0.57
2025-08-06 08:42:43,437 - INFO - Validated that VN service-left-vn-49801224 is not in any agent
2025-08-06 08:42:43,487 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-49801224 info
2025-08-06 08:42:43,488 - INFO - Deleting VN ctest-mgmt_vn-60651251
2025-08-06 08:42:43,684 - DEBUG - Response for deleting network ()
2025-08-06 08:42:43,684 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/2186ab0c-d7a7-4594-a389-a7d9d312749d
2025-08-06 08:42:43,691 - DEBUG - Response Code: 404
2025-08-06 08:42:43,692 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:43,699 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:43,761 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:43,868 - INFO - Validated that VN ctest-mgmt_vn-60651251 is not found in API Server
2025-08-06 08:42:43,882 - DEBUG - VN ctest-mgmt_vn-60651251 is not present in Agent 10.0.0.49
2025-08-06 08:42:43,897 - DEBUG - VN ctest-mgmt_vn-60651251 is not present in Agent 10.0.0.57
2025-08-06 08:42:43,897 - INFO - Validated that VN ctest-mgmt_vn-60651251 is not in any agent
2025-08-06 08:42:43,936 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-60651251 info
2025-08-06 08:42:45,225 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 08:42:45,225 - INFO -
2025-08-06 08:42:45,225 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:17]
2025-08-06 08:42:45,225 - INFO - --------------------------------------------------------------------------------
2025-08-06 08:42:45,229 - INFO - ================================================================================
2025-08-06 08:42:45,229 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-06 08:42:46,516 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.14': '0', '10.20.0.254': '0'}} with
2025-08-06 08:42:46,517 - INFO - Initial checks done. Running the testcase now
2025-08-06 08:42:46,517 - INFO -
2025-08-06 08:42:46,925 - DEBUG - Response for create_network : {'network': {'id': 'f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b', 'name': 'ctest-mgmt_vn-87785205', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'ctest-mgmt_vn-87785205'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:47,284 - DEBUG - Response for create_subnet : {'subnet': {'id': '73f4993f-c5d8-4d93-b503-9fe12f306c31', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': 'f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b', 'ip_version': 4, 'cidr': '49.24.242.64/26', 'allocation_pools': [{'start': '49.24.242.66', 'end': '49.24.242.126'}], 'gateway_ip': '49.24.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.24.242.66', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:47,317 - INFO - Created VN ctest-mgmt_vn-87785205
2025-08-06 08:42:47,338 - DEBUG - VN ctest-mgmt_vn-87785205 UUID is f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,446 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:47,457 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:47,478 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:47,617 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,637 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,657 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:42:47,670 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:42:47,685 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/e2708607-3097-43f2-b689-edcf1218b91a
2025-08-06 08:42:47,695 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:47,695 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,710 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:42:47,735 - INFO - Verified VN network id 15 for VN f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,735 - INFO - Verifications in API Server for VN ctest-mgmt_vn-87785205 passed
2025-08-06 08:42:47,736 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:47,752 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:42:47,776 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:42:47,793 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/e2708607-3097-43f2-b689-edcf1218b91a
2025-08-06 08:42:47,811 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17351474169028299178', 'uuid-lslong': '11918157826810700395'}, 'enable': 'true', 'created': '2025-08-06T08:42:46', 'last-modified': '2025-08-06T08:42:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.609468'}
2025-08-06 08:42:47,827 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:47,847 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17351474169028299178', 'uuid-lslong': '11918157826810700395'}, 'enable': 'true', 'created': '2025-08-06T08:42:46', 'last-modified': '2025-08-06T08:42:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.650537'}
2025-08-06 08:42:47,871 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:47,878 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17351474169028299178', 'uuid-lslong': '11918157826810700395'}, 'enable': 'true', 'created': '2025-08-06T08:42:46', 'last-modified': '2025-08-06T08:42:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.675250'}
2025-08-06 08:42:47,885 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 08:42:47,885 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-87785205 passed
2025-08-06 08:42:47,885 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-87785205 in API_Server ======
2025-08-06 08:42:47,885 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:47,892 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:47,907 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:48,043 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/f0ccd1d3-1e4c-49aa-a565-cd65fc17e26b
2025-08-06 08:42:48,064 - DEBUG - =>VN ctest-mgmt_vn-87785205 has no policy to be verified
2025-08-06 08:42:48,064 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:48,064 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:48,064 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:48,087 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205?flat'}
2025-08-06 08:42:48,087 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:48,087 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:48,108 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205?flat'}
2025-08-06 08:42:48,108 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:48,108 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:48,133 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205?flat'}
2025-08-06 08:42:48,133 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:ctest-mgmt_vn-87785205 is found in opserver
2025-08-06 08:42:48,167 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:48,181 - DEBUG - VRF ids for VN ctest-mgmt_vn-87785205: {}
2025-08-06 08:42:48,594 - DEBUG - Response for create_network : {'network': {'id': '67eea4be-13dd-485f-80e9-e127951b0e28', 'name': 'ctest-left_vn-13072749', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'ctest-left_vn-13072749'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:48,960 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f6d213cc-e33b-4eb9-b529-cebc5cea0d97', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': '67eea4be-13dd-485f-80e9-e127951b0e28', 'ip_version': 4, 'cidr': '113.253.83.64/26', 'allocation_pools': [{'start': '113.253.83.66', 'end': '113.253.83.126'}], 'gateway_ip': '113.253.83.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '113.253.83.66', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:48,996 - INFO - Created VN ctest-left_vn-13072749
2025-08-06 08:42:49,009 - DEBUG - VN ctest-left_vn-13072749 UUID is 67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,136 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:49,149 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:49,184 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:49,370 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,400 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,418 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:49,459 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:49,479 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/9eeaf834-dec5-4609-bf50-377c279f0cf3
2025-08-06 08:42:49,500 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:49,500 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,518 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:49,544 - INFO - Verified VN network id 16 for VN 67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,544 - INFO - Verifications in API Server for VN ctest-left_vn-13072749 passed
2025-08-06 08:42:49,544 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,568 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:49,588 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:49,610 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/9eeaf834-dec5-4609-bf50-377c279f0cf3
2025-08-06 08:42:49,634 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7489104366647724127', 'uuid-lslong': '9289203266549911080'}, 'enable': 'true', 'created': '2025-08-06T08:42:48', 'last-modified': '2025-08-06T08:42:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.776720'}
2025-08-06 08:42:49,659 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:49,669 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7489104366647724127', 'uuid-lslong': '9289203266549911080'}, 'enable': 'true', 'created': '2025-08-06T08:42:48', 'last-modified': '2025-08-06T08:42:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.812045'}
2025-08-06 08:42:49,682 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:49,692 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7489104366647724127', 'uuid-lslong': '9289203266549911080'}, 'enable': 'true', 'created': '2025-08-06T08:42:48', 'last-modified': '2025-08-06T08:42:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.848501'}
2025-08-06 08:42:49,702 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 08:42:49,702 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-13072749 passed
2025-08-06 08:42:49,702 - DEBUG - ====Verifying policy data for ctest-left_vn-13072749 in API_Server ======
2025-08-06 08:42:49,702 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:49,717 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:49,741 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:49,861 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/67eea4be-13dd-485f-80e9-e127951b0e28
2025-08-06 08:42:49,882 - DEBUG - =>VN ctest-left_vn-13072749 has no policy to be verified
2025-08-06 08:42:49,883 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:49,883 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:49,883 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:49,900 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749?flat'}
2025-08-06 08:42:49,900 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:49,900 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:49,920 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749?flat'}
2025-08-06 08:42:49,920 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:49,920 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:49,943 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749?flat'}
2025-08-06 08:42:49,943 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:ctest-left_vn-13072749 is found in opserver
2025-08-06 08:42:49,970 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:49,984 - DEBUG - VRF ids for VN ctest-left_vn-13072749: {}
2025-08-06 08:42:50,335 - DEBUG - Response for create_network : {'network': {'id': '8ce74afc-0c83-4c5a-b428-b49fbbda3335', 'name': 'ctest-right_vn-68190520', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87351752', 'ctest-right_vn-68190520'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 08:42:50,659 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c4afdf0d-7e6e-4b8a-9d8f-6e1d47b94e1b', 'name': '', 'tenant_id': 'c95f27a3c88b44b289aaebb7be3106a8', 'network_id': '8ce74afc-0c83-4c5a-b428-b49fbbda3335', 'ip_version': 4, 'cidr': '18.179.78.192/26', 'allocation_pools': [{'start': '18.179.78.194', 'end': '18.179.78.254'}], 'gateway_ip': '18.179.78.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '18.179.78.194', 'tags': [], 'project_id': 'c95f27a3c88b44b289aaebb7be3106a8'}}
2025-08-06 08:42:50,703 - INFO - Created VN ctest-right_vn-68190520
2025-08-06 08:42:50,718 - DEBUG - VN ctest-right_vn-68190520 UUID is 8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:50,855 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:50,873 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:50,906 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:51,041 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,076 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,104 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:51,125 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:51,149 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/c9c4474f-a16b-44df-9c51-7fa97d894701
2025-08-06 08:42:51,172 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:51,172 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,205 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:51,231 - INFO - Verified VN network id 17 for VN 8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,231 - INFO - Verifications in API Server for VN ctest-right_vn-68190520 passed
2025-08-06 08:42:51,231 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,250 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:51,262 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:51,279 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/c9c4474f-a16b-44df-9c51-7fa97d894701
2025-08-06 08:42:51,302 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10153166331332217946', 'uuid-lslong': '12981824524039893813'}, 'enable': 'true', 'created': '2025-08-06T08:42:50', 'last-modified': '2025-08-06T08:42:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.712668'}
2025-08-06 08:42:51,310 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:51,318 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10153166331332217946', 'uuid-lslong': '12981824524039893813'}, 'enable': 'true', 'created': '2025-08-06T08:42:50', 'last-modified': '2025-08-06T08:42:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.729465'}
2025-08-06 08:42:51,327 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:51,336 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87351752', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10153166331332217946', 'uuid-lslong': '12981824524039893813'}, 'enable': 'true', 'created': '2025-08-06T08:42:50', 'last-modified': '2025-08-06T08:42:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.743533'}
2025-08-06 08:42:51,348 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 08:42:51,348 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-68190520 passed
2025-08-06 08:42:51,348 - DEBUG - ====Verifying policy data for ctest-right_vn-68190520 in API_Server ======
2025-08-06 08:42:51,348 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:51,360 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:51,397 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:51,535 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/8ce74afc-0c83-4c5a-b428-b49fbbda3335
2025-08-06 08:42:51,567 - DEBUG - =>VN ctest-right_vn-68190520 has no policy to be verified
2025-08-06 08:42:51,567 - DEBUG - Verifying the vn in opserver
2025-08-06 08:42:51,567 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520 virtual network link through opserver 10.0.0.48
2025-08-06 08:42:51,567 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 08:42:51,600 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520?flat'}
2025-08-06 08:42:51,600 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520 virtual network link through opserver 10.0.0.254
2025-08-06 08:42:51,600 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 08:42:51,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520?flat'}
2025-08-06 08:42:51,621 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520 virtual network link through opserver 10.0.0.38
2025-08-06 08:42:51,621 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 08:42:51,643 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520?flat'}
2025-08-06 08:42:51,643 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87351752:ctest-right_vn-68190520 is found in opserver
2025-08-06 08:42:51,690 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 08:42:51,704 - DEBUG - VRF ids for VN ctest-right_vn-68190520: {}
2025-08-06 08:42:53,401 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-2)
2025-08-06 08:42:55,452 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3761-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3761-1)
2025-08-06 08:42:55,452 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-77047487']
2025-08-06 08:42:55,758 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-77047487'] created succesfully
2025-08-06 08:42:56,439 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 08:42:56,439 - DEBUG - Installing image tiny_nat_fw
2025-08-06 08:42:56,439 - 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-08-06 08:42:56,439 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:56,439 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:56,439 - DEBUG - Unzip image
2025-08-06 08:42:56,439 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 08:42:56,485 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 08:42:56,486 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-77047487']
2025-08-06 08:42:56,618 - INFO - Service template: ['default-domain', 'ctest-service_template_1-77047487'] deleted successfully.
2025-08-06 08:42:56,618 - INFO - Skip interface_detach for VM ctest-right_vm-75696450 in state BUILD
2025-08-06 08:42:56,618 - INFO - Deleting VM ctest-right_vm-75696450
2025-08-06 08:42:56,619 - ERROR - VM ctest-right_vm-75696450 has failed to come up
2025-08-06 08:42:56,619 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:56,619 - ERROR - VM ctest-right_vm-75696450 has failed to come up
2025-08-06 08:42:56,619 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:56,787 - INFO - Skip interface_detach for VM ctest-left_vm-67242925 in state BUILD
2025-08-06 08:42:56,787 - INFO - Deleting VM ctest-left_vm-67242925
2025-08-06 08:42:56,787 - ERROR - VM ctest-left_vm-67242925 has failed to come up
2025-08-06 08:42:56,787 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:56,787 - ERROR - VM ctest-left_vm-67242925 has failed to come up
2025-08-06 08:42:56,787 - ERROR - Nova failed to get host of the VM
2025-08-06 08:42:56,892 - INFO - Deleting VN ctest-right_vn-68190520
2025-08-06 08:42:56,944 - DEBUG - VN 8ce74afc-0c83-4c5a-b428-b49fbbda3335 still in use: Unable to complete operation on network 8ce74afc-0c83-4c5a-b428-b49fbbda3335. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dbe7945f-446f-4b6e-87c8-c9a12388c7fa']
2025-08-06 08:42:56,945 - WARNING - Deleting VN ctest-right_vn-68190520 failed..Will retry
2025-08-06 08:42:59,128 - DEBUG - Response for deleting network ()
2025-08-06 08:42:59,129 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6213254f-f29c-4fbd-a3c5-6b29ae64650d
2025-08-06 08:42:59,140 - DEBUG - Response Code: 404
2025-08-06 08:42:59,140 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:59,148 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:59,184 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:59,284 - INFO - Validated that VN ctest-right_vn-68190520 is not found in API Server
2025-08-06 08:42:59,300 - DEBUG - VN ctest-right_vn-68190520 is not present in Agent 10.0.0.49
2025-08-06 08:42:59,316 - DEBUG - VN ctest-right_vn-68190520 is not present in Agent 10.0.0.57
2025-08-06 08:42:59,316 - INFO - Validated that VN ctest-right_vn-68190520 is not in any agent
2025-08-06 08:42:59,355 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-68190520 info
2025-08-06 08:42:59,355 - INFO - Deleting VN ctest-left_vn-13072749
2025-08-06 08:42:59,547 - DEBUG - Response for deleting network ()
2025-08-06 08:42:59,547 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/6397e417-83a9-447b-af01-c8f6f36ca201
2025-08-06 08:42:59,560 - DEBUG - Response Code: 404
2025-08-06 08:42:59,560 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:42:59,572 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:42:59,596 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:42:59,718 - INFO - Validated that VN ctest-left_vn-13072749 is not found in API Server
2025-08-06 08:42:59,732 - DEBUG - VN ctest-left_vn-13072749 is not present in Agent 10.0.0.49
2025-08-06 08:42:59,743 - DEBUG - VN ctest-left_vn-13072749 is not present in Agent 10.0.0.57
2025-08-06 08:42:59,743 - INFO - Validated that VN ctest-left_vn-13072749 is not in any agent
2025-08-06 08:42:59,786 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-13072749 info
2025-08-06 08:42:59,786 - INFO - Deleting VN ctest-mgmt_vn-87785205
2025-08-06 08:43:00,138 - DEBUG - Response for deleting network ()
2025-08-06 08:43:00,139 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/95cc8910-90e6-4a55-9150-1ab337b56015
2025-08-06 08:43:00,150 - DEBUG - Response Code: 404
2025-08-06 08:43:00,150 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-08-06 08:43:00,160 - DEBUG - Requesting: http://10.0.0.48:8082/domain/e3e88518-0617-4e53-bc15-162decab76e0
2025-08-06 08:43:00,181 - DEBUG - Requesting: http://10.0.0.48:8082/project/c95f27a3-c88b-44b2-89aa-ebb7be3106a8
2025-08-06 08:43:00,295 - INFO - Validated that VN ctest-mgmt_vn-87785205 is not found in API Server
2025-08-06 08:43:00,308 - DEBUG - VN ctest-mgmt_vn-87785205 is not present in Agent 10.0.0.49
2025-08-06 08:43:00,324 - DEBUG - VN ctest-mgmt_vn-87785205 is not present in Agent 10.0.0.57
2025-08-06 08:43:00,324 - INFO - Validated that VN ctest-mgmt_vn-87785205 is not in any agent
2025-08-06 08:43:00,363 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-87785205 info
2025-08-06 08:43:01,678 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 08:43:01,678 - INFO -
2025-08-06 08:43:01,678 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:16]
2025-08-06 08:43:01,678 - INFO - --------------------------------------------------------------------------------
2025-08-06 08:43:02,472 - INFO - Deleted project: ctest-TestSvcRegr-87351752, ID : c95f27a3-c88b-44b2-89aa-ebb7be3106a8