]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1)
2025-11-10 02:57:55,712 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-28730382']
2025-11-10 02:57:56,041 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-28730382'] created succesfully
2025-11-10 02:57:56,472 - DEBUG - Image by name tiny_nat_fw not found
2025-11-10 02:57:56,472 - DEBUG - Installing image tiny_nat_fw
2025-11-10 02:57:56,472 - 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-11-10 02:57:56,473 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-10 02:57:56,473 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-11-10 02:57:56,498 - 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-11-10 02:57:56,660 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:57:56,660 - DEBUG - Unzip image
2025-11-10 02:57:56,661 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:57:56,685 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-10 02:57:56,686 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-28730382']
2025-11-10 02:57:56,784 - INFO - Service template: ['default-domain', 'ctest-service_template_1-28730382'] deleted successfully.
2025-11-10 02:57:56,785 - INFO - Skip interface_detach for VM ctest-right_vm-38965446 in state BUILD
2025-11-10 02:57:56,785 - INFO - Deleting VM ctest-right_vm-38965446
2025-11-10 02:57:56,837 - ERROR - VM ctest-right_vm-38965446 has failed to come up
2025-11-10 02:57:56,840 - ERROR - Nova failed to get host of the VM
2025-11-10 02:57:56,840 - ERROR - VM ctest-right_vm-38965446 has failed to come up
2025-11-10 02:57:56,840 - ERROR - Nova failed to get host of the VM
2025-11-10 02:57:56,921 - INFO - Skip interface_detach for VM ctest-left_vm-45738108 in state BUILD
2025-11-10 02:57:56,921 - INFO - Deleting VM ctest-left_vm-45738108
2025-11-10 02:57:56,921 - ERROR - VM ctest-left_vm-45738108 has failed to come up
2025-11-10 02:57:56,921 - ERROR - Nova failed to get host of the VM
2025-11-10 02:57:56,921 - ERROR - VM ctest-left_vm-45738108 has failed to come up
2025-11-10 02:57:56,921 - ERROR - Nova failed to get host of the VM
2025-11-10 02:57:56,992 - INFO - Deleting VN ctest-right_vn-56661984
2025-11-10 02:57:57,036 - DEBUG - VN 8b8b1f38-060a-4eb1-b777-29086c5aaf69 still in use: Unable to complete operation on network 8b8b1f38-060a-4eb1-b777-29086c5aaf69. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4645be29-35d8-4862-b96b-cafba10577a8']
2025-11-10 02:57:57,036 - WARNING - Deleting VN ctest-right_vn-56661984 failed..Will retry
2025-11-10 02:57:59,238 - DEBUG - Response for deleting network ()
2025-11-10 02:57:59,238 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/923a3311-3b29-4642-9fbf-844fd3a91e1c
2025-11-10 02:57:59,255 - DEBUG - Response Code: 404
2025-11-10 02:57:59,255 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:57:59,274 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:57:59,295 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:57:59,404 - INFO - Validated that VN ctest-right_vn-56661984 is not found in API Server
2025-11-10 02:57:59,419 - DEBUG - VN ctest-right_vn-56661984 is not present in Agent 10.0.0.65
2025-11-10 02:57:59,429 - DEBUG - VN ctest-right_vn-56661984 is not present in Agent 10.0.0.130
2025-11-10 02:57:59,429 - INFO - Validated that VN ctest-right_vn-56661984 is not in any agent
2025-11-10 02:57:59,468 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-56661984 info
2025-11-10 02:57:59,468 - INFO - Deleting VN ctest-left_vn-55121993
2025-11-10 02:57:59,687 - DEBUG - Response for deleting network ()
2025-11-10 02:57:59,687 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ae3971a9-1526-435a-9404-b606a9188409
2025-11-10 02:57:59,704 - DEBUG - Response Code: 404
2025-11-10 02:57:59,705 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:57:59,714 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:57:59,728 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:57:59,820 - INFO - Validated that VN ctest-left_vn-55121993 is not found in API Server
2025-11-10 02:57:59,832 - DEBUG - VN ctest-left_vn-55121993 is not present in Agent 10.0.0.65
2025-11-10 02:57:59,842 - DEBUG - VN ctest-left_vn-55121993 is not present in Agent 10.0.0.130
2025-11-10 02:57:59,842 - INFO - Validated that VN ctest-left_vn-55121993 is not in any agent
2025-11-10 02:57:59,880 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-55121993 info
2025-11-10 02:57:59,880 - INFO - Deleting VN ctest-mgmt_vn-99436664
2025-11-10 02:58:00,066 - DEBUG - Response for deleting network ()
2025-11-10 02:58:00,067 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/129d38a3-a69f-4bc2-a9eb-2b3463bcf2f7
2025-11-10 02:58:00,075 - DEBUG - Response Code: 404
2025-11-10 02:58:00,075 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:00,087 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:00,106 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:00,198 - INFO - Validated that VN ctest-mgmt_vn-99436664 is not found in API Server
2025-11-10 02:58:00,214 - DEBUG - VN ctest-mgmt_vn-99436664 is not present in Agent 10.0.0.65
2025-11-10 02:58:00,228 - DEBUG - VN ctest-mgmt_vn-99436664 is not present in Agent 10.0.0.130
2025-11-10 02:58:00,228 - INFO - Validated that VN ctest-mgmt_vn-99436664 is not in any agent
2025-11-10 02:58:00,264 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99436664 info
2025-11-10 02:58:00,264 - INFO - Deleting VM ctest-vm-in-third-vn-20381695
2025-11-10 02:58:00,355 - INFO - Deleting VN ctest-third-vn-39885783
2025-11-10 02:58:00,441 - DEBUG - VN 8c46ee3c-33f8-4503-a983-dd155af18afe still in use: Unable to complete operation on network 8c46ee3c-33f8-4503-a983-dd155af18afe. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7e5db47c-b142-43a8-8a06-5d9e064fbb76']
2025-11-10 02:58:00,441 - WARNING - Deleting VN ctest-third-vn-39885783 failed..Will retry
2025-11-10 02:58:02,636 - DEBUG - Response for deleting network ()
2025-11-10 02:58:03,902 - DEBUG - No XMPP flaps were noticed during the test
2025-11-10 02:58:03,902 - INFO -
2025-11-10 02:58:03,902 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-11-10 02:58:03,902 - INFO - --------------------------------------------------------------------------------
2025-11-10 02:58:03,905 - INFO - ================================================================================
2025-11-10 02:58:03,905 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-11-10 02:58:05,186 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-11-10 02:58:05,186 - INFO - Initial checks done. Running the testcase now
2025-11-10 02:58:05,186 - INFO -
2025-11-10 02:58:05,482 - DEBUG - Response for create_network : {'network': {'id': '33c4fadb-f7a1-470d-befe-92e79e1b5799', 'name': 'ctest-mgmt_vn-87570736', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47574789', 'ctest-mgmt_vn-87570736'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:05,761 - DEBUG - Response for create_subnet : {'subnet': {'id': '798279b4-8acd-4135-888e-f7b6f3049e6f', 'name': '', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'network_id': '33c4fadb-f7a1-470d-befe-92e79e1b5799', 'ip_version': 4, 'cidr': '62.156.6.192/26', 'allocation_pools': [{'start': '62.156.6.194', 'end': '62.156.6.254'}], 'gateway_ip': '62.156.6.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.156.6.194', 'tags': [], 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a'}}
2025-11-10 02:58:05,783 - INFO - Created VN ctest-mgmt_vn-87570736
2025-11-10 02:58:05,798 - DEBUG - VN ctest-mgmt_vn-87570736 UUID is 33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:05,878 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:05,885 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:05,897 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:05,991 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,006 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,017 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:06,024 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:06,033 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d24af96e-cc06-4712-b04c-87e5df1086cc
2025-11-10 02:58:06,042 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:06,042 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,051 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:06,069 - INFO - Verified VN network id 15 for VN 33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,069 - INFO - Verifications in API Server for VN ctest-mgmt_vn-87570736 passed
2025-11-10 02:58:06,069 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,079 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:06,087 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:06,094 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d24af96e-cc06-4712-b04c-87e5df1086cc
2025-11-10 02:58:06,108 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3730382214028936973', 'uuid-lslong': '13762599034778507161'}, 'enable': 'true', 'created': '2025-11-10T02:58:05', 'last-modified': '2025-11-10T02:58:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424858'}
2025-11-10 02:58:06,118 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:06,124 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3730382214028936973', 'uuid-lslong': '13762599034778507161'}, 'enable': 'true', 'created': '2025-11-10T02:58:05', 'last-modified': '2025-11-10T02:58:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441095'}
2025-11-10 02:58:06,132 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:06,137 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3730382214028936973', 'uuid-lslong': '13762599034778507161'}, 'enable': 'true', 'created': '2025-11-10T02:58:05', 'last-modified': '2025-11-10T02:58:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454514'}
2025-11-10 02:58:06,144 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:06,144 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-87570736 passed
2025-11-10 02:58:06,144 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-87570736 in API_Server ======
2025-11-10 02:58:06,144 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:06,152 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:06,165 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:06,258 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/33c4fadb-f7a1-470d-befe-92e79e1b5799
2025-11-10 02:58:06,271 - DEBUG - =>VN ctest-mgmt_vn-87570736 has no policy to be verified
2025-11-10 02:58:06,271 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:06,271 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:06,271 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:06,286 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736?flat'}
2025-11-10 02:58:06,286 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:06,287 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:06,302 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736?flat'}
2025-11-10 02:58:06,302 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:06,302 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:06,316 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736?flat'}
2025-11-10 02:58:06,316 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47574789:ctest-mgmt_vn-87570736 is found in opserver
2025-11-10 02:58:06,342 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:06,355 - DEBUG - VRF ids for VN ctest-mgmt_vn-87570736: {}
2025-11-10 02:58:06,628 - DEBUG - Response for create_network : {'network': {'id': '70c478b8-c57f-466e-93ce-913b40fced3f', 'name': 'ctest-left_vn-70355567', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47574789', 'ctest-left_vn-70355567'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:06,875 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c67f544e-f5dc-4bff-ad88-7dd1c2ff59d5', 'name': '', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'network_id': '70c478b8-c57f-466e-93ce-913b40fced3f', 'ip_version': 4, 'cidr': '150.22.53.128/26', 'allocation_pools': [{'start': '150.22.53.130', 'end': '150.22.53.190'}], 'gateway_ip': '150.22.53.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.22.53.130', 'tags': [], 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a'}}
2025-11-10 02:58:06,907 - INFO - Created VN ctest-left_vn-70355567
2025-11-10 02:58:06,919 - DEBUG - VN ctest-left_vn-70355567 UUID is 70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,019 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:07,027 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:07,039 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:07,133 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,145 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,154 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:07,165 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:07,173 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4d64dfe2-c3d8-449b-b383-2fd636670521
2025-11-10 02:58:07,180 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:07,180 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,191 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:07,207 - INFO - Verified VN network id 16 for VN 70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,208 - INFO - Verifications in API Server for VN ctest-left_vn-70355567 passed
2025-11-10 02:58:07,208 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,217 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:07,226 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:07,233 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4d64dfe2-c3d8-449b-b383-2fd636670521
2025-11-10 02:58:07,245 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8125752362665985646', 'uuid-lslong': '10650609852457217343'}, 'enable': 'true', 'created': '2025-11-10T02:58:06', 'last-modified': '2025-11-10T02:58:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441508'}
2025-11-10 02:58:07,252 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:07,258 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8125752362665985646', 'uuid-lslong': '10650609852457217343'}, 'enable': 'true', 'created': '2025-11-10T02:58:06', 'last-modified': '2025-11-10T02:58:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453628'}
2025-11-10 02:58:07,266 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:07,273 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8125752362665985646', 'uuid-lslong': '10650609852457217343'}, 'enable': 'true', 'created': '2025-11-10T02:58:06', 'last-modified': '2025-11-10T02:58:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476269'}
2025-11-10 02:58:07,279 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:07,279 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-70355567 passed
2025-11-10 02:58:07,280 - DEBUG - ====Verifying policy data for ctest-left_vn-70355567 in API_Server ======
2025-11-10 02:58:07,280 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:07,287 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:07,306 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:07,408 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/70c478b8-c57f-466e-93ce-913b40fced3f
2025-11-10 02:58:07,421 - DEBUG - =>VN ctest-left_vn-70355567 has no policy to be verified
2025-11-10 02:58:07,421 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:07,422 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:07,422 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:07,435 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567?flat'}
2025-11-10 02:58:07,435 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:07,435 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:07,449 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567?flat'}
2025-11-10 02:58:07,449 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:07,449 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:07,463 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567?flat'}
2025-11-10 02:58:07,463 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47574789:ctest-left_vn-70355567 is found in opserver
2025-11-10 02:58:07,485 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:07,495 - DEBUG - VRF ids for VN ctest-left_vn-70355567: {}
2025-11-10 02:58:07,789 - DEBUG - Response for create_network : {'network': {'id': 'c5aaa4df-24d3-4425-9f34-869d0893ca66', 'name': 'ctest-right_vn-56421494', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47574789', 'ctest-right_vn-56421494'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:08,047 - DEBUG - Response for create_subnet : {'subnet': {'id': '09040dbe-d4ac-4fcd-86b7-df86a80eb1bc', 'name': '', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'network_id': 'c5aaa4df-24d3-4425-9f34-869d0893ca66', 'ip_version': 4, 'cidr': '209.54.39.64/26', 'allocation_pools': [{'start': '209.54.39.66', 'end': '209.54.39.126'}], 'gateway_ip': '209.54.39.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '209.54.39.66', 'tags': [], 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a'}}
2025-11-10 02:58:08,070 - INFO - Created VN ctest-right_vn-56421494
2025-11-10 02:58:08,083 - DEBUG - VN ctest-right_vn-56421494 UUID is c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,177 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:08,185 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:08,200 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:08,306 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,322 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,332 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:08,343 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:08,350 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/80a42bbf-1cd3-4835-8b64-6a85f28629f9
2025-11-10 02:58:08,360 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:08,360 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,372 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:08,389 - INFO - Verified VN network id 17 for VN c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,389 - INFO - Verifications in API Server for VN ctest-right_vn-56421494 passed
2025-11-10 02:58:08,389 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,399 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:08,408 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:08,418 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/80a42bbf-1cd3-4835-8b64-6a85f28629f9
2025-11-10 02:58:08,433 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14243378049815102501', 'uuid-lslong': '11471942159831386726'}, 'enable': 'true', 'created': '2025-11-10T02:58:07', 'last-modified': '2025-11-10T02:58:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.444509'}
2025-11-10 02:58:08,444 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:08,452 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14243378049815102501', 'uuid-lslong': '11471942159831386726'}, 'enable': 'true', 'created': '2025-11-10T02:58:07', 'last-modified': '2025-11-10T02:58:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463170'}
2025-11-10 02:58:08,458 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:08,463 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14243378049815102501', 'uuid-lslong': '11471942159831386726'}, 'enable': 'true', 'created': '2025-11-10T02:58:07', 'last-modified': '2025-11-10T02:58:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476056'}
2025-11-10 02:58:08,469 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:08,469 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-56421494 passed
2025-11-10 02:58:08,470 - DEBUG - ====Verifying policy data for ctest-right_vn-56421494 in API_Server ======
2025-11-10 02:58:08,470 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:08,476 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:08,488 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:08,585 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c5aaa4df-24d3-4425-9f34-869d0893ca66
2025-11-10 02:58:08,603 - DEBUG - =>VN ctest-right_vn-56421494 has no policy to be verified
2025-11-10 02:58:08,603 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:08,604 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:08,604 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:08,621 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494?flat'}
2025-11-10 02:58:08,621 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:08,621 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:08,638 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494?flat'}
2025-11-10 02:58:08,638 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:08,638 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:08,651 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494?flat'}
2025-11-10 02:58:08,651 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47574789:ctest-right_vn-56421494 is found in opserver
2025-11-10 02:58:08,673 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:08,683 - DEBUG - VRF ids for VN ctest-right_vn-56421494: {}
2025-11-10 02:58:09,004 - DEBUG - Response for create_network : {'network': {'id': '0527f147-a716-49e6-9b50-ae2f380499e4', 'name': 'ctest-trans_left_vn-03900649', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47574789', 'ctest-trans_left_vn-03900649'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:09,228 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d45d85f-d0a3-40ab-b36e-054082391e96', 'name': '', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'network_id': '0527f147-a716-49e6-9b50-ae2f380499e4', 'ip_version': 4, 'cidr': '90.97.93.0/26', 'allocation_pools': [{'start': '90.97.93.2', 'end': '90.97.93.62'}], 'gateway_ip': '90.97.93.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.97.93.2', 'tags': [], 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a'}}
2025-11-10 02:58:09,257 - INFO - Created VN ctest-trans_left_vn-03900649
2025-11-10 02:58:09,273 - DEBUG - VN ctest-trans_left_vn-03900649 UUID is 0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,354 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:09,362 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:09,373 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:09,472 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,490 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,502 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:09,510 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:09,518 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4cd25411-2f97-4f35-9e93-e84ad98a1a55
2025-11-10 02:58:09,532 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-10 02:58:09,532 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,543 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:09,560 - INFO - Verified VN network id 18 for VN 0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,560 - INFO - Verifications in API Server for VN ctest-trans_left_vn-03900649 passed
2025-11-10 02:58:09,560 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,573 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:09,580 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:09,587 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/4cd25411-2f97-4f35-9e93-e84ad98a1a55
2025-11-10 02:58:09,601 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '371530784329583078', 'uuid-lslong': '11191636591842204132'}, 'enable': 'true', 'created': '2025-11-10T02:58:08', 'last-modified': '2025-11-10T02:58:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.412145'}
2025-11-10 02:58:09,607 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-10 02:58:09,613 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '371530784329583078', 'uuid-lslong': '11191636591842204132'}, 'enable': 'true', 'created': '2025-11-10T02:58:08', 'last-modified': '2025-11-10T02:58:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433168'}
2025-11-10 02:58:09,623 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-10 02:58:09,629 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '371530784329583078', 'uuid-lslong': '11191636591842204132'}, 'enable': 'true', 'created': '2025-11-10T02:58:08', 'last-modified': '2025-11-10T02:58:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453348'}
2025-11-10 02:58:09,636 - DEBUG - Route Targets: ['target:64512:8000008']
2025-11-10 02:58:09,636 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-03900649 passed
2025-11-10 02:58:09,637 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-03900649 in API_Server ======
2025-11-10 02:58:09,637 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:09,643 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:09,657 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:09,754 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/0527f147-a716-49e6-9b50-ae2f380499e4
2025-11-10 02:58:09,764 - DEBUG - =>VN ctest-trans_left_vn-03900649 has no policy to be verified
2025-11-10 02:58:09,764 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:09,764 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:09,764 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:09,776 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649?flat'}
2025-11-10 02:58:09,776 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:09,776 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:09,791 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649?flat'}
2025-11-10 02:58:09,791 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:09,791 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:09,805 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649?flat'}
2025-11-10 02:58:09,805 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47574789:ctest-trans_left_vn-03900649 is found in opserver
2025-11-10 02:58:09,831 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:09,844 - DEBUG - VRF ids for VN ctest-trans_left_vn-03900649: {}
2025-11-10 02:58:10,129 - DEBUG - Response for create_network : {'network': {'id': '1da4b61f-3fa7-41d2-9ba4-69098dd46669', 'name': 'ctest-trans_right_vn-27497126', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-47574789', 'ctest-trans_right_vn-27497126'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:10,390 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e6810795-d37b-42fc-8aa2-73b468c5094a', 'name': '', 'tenant_id': 'ac8a00e2ecbe4252981014750bee4f7a', 'network_id': '1da4b61f-3fa7-41d2-9ba4-69098dd46669', 'ip_version': 4, 'cidr': '186.243.183.0/26', 'allocation_pools': [{'start': '186.243.183.2', 'end': '186.243.183.62'}], 'gateway_ip': '186.243.183.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '186.243.183.2', 'tags': [], 'project_id': 'ac8a00e2ecbe4252981014750bee4f7a'}}
2025-11-10 02:58:10,413 - INFO - Created VN ctest-trans_right_vn-27497126
2025-11-10 02:58:10,424 - DEBUG - VN ctest-trans_right_vn-27497126 UUID is 1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,506 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:10,514 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:10,528 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:10,639 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,652 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,662 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:10,674 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:10,685 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/bc948576-8781-480b-b244-c005a2950ca1
2025-11-10 02:58:10,702 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-10 02:58:10,702 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,714 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:10,735 - INFO - Verified VN network id 19 for VN 1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,735 - INFO - Verifications in API Server for VN ctest-trans_right_vn-27497126 passed
2025-11-10 02:58:10,736 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,748 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:10,760 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:10,770 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/bc948576-8781-480b-b244-c005a2950ca1
2025-11-10 02:58:10,786 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2136032368608625106', 'uuid-lslong': '11215204461814507113'}, 'enable': 'true', 'created': '2025-11-10T02:58:10', 'last-modified': '2025-11-10T02:58:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.463735'}
2025-11-10 02:58:10,795 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-10 02:58:10,803 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2136032368608625106', 'uuid-lslong': '11215204461814507113'}, 'enable': 'true', 'created': '2025-11-10T02:58:10', 'last-modified': '2025-11-10T02:58:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481264'}
2025-11-10 02:58:10,812 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-10 02:58:10,818 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-47574789', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2136032368608625106', 'uuid-lslong': '11215204461814507113'}, 'enable': 'true', 'created': '2025-11-10T02:58:10', 'last-modified': '2025-11-10T02:58:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.498435'}
2025-11-10 02:58:10,825 - DEBUG - Route Targets: ['target:64512:8000009']
2025-11-10 02:58:10,825 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-27497126 passed
2025-11-10 02:58:10,826 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-27497126 in API_Server ======
2025-11-10 02:58:10,826 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:10,834 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:10,846 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:10,949 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1da4b61f-3fa7-41d2-9ba4-69098dd46669
2025-11-10 02:58:10,959 - DEBUG - =>VN ctest-trans_right_vn-27497126 has no policy to be verified
2025-11-10 02:58:10,959 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:10,959 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:10,959 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:10,971 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126?flat'}
2025-11-10 02:58:10,972 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:10,972 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:10,988 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126?flat'}
2025-11-10 02:58:10,988 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:10,988 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:11,009 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126?flat'}
2025-11-10 02:58:11,009 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-47574789:ctest-trans_right_vn-27497126 is found in opserver
2025-11-10 02:58:11,035 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:11,045 - DEBUG - VRF ids for VN ctest-trans_right_vn-27497126: {}
2025-11-10 02:58:12,139 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1)
2025-11-10 02:58:13,557 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2)
2025-11-10 02:58:13,558 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72551202']
2025-11-10 02:58:13,857 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72551202'] created succesfully
2025-11-10 02:58:14,230 - DEBUG - Image by name tiny_trans_fw not found
2025-11-10 02:58:14,230 - DEBUG - Installing image tiny_trans_fw
2025-11-10 02:58:14,230 - 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-11-10 02:58:14,231 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-10 02:58:14,231 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-11-10 02:58:14,257 - 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-11-10 02:58:14,319 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-10 02:58:14,320 - DEBUG - Unzip image
2025-11-10 02:58:14,320 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-11-10 02:58:14,346 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-11-10 02:58:14,348 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72551202']
2025-11-10 02:58:14,452 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72551202'] deleted successfully.
2025-11-10 02:58:14,453 - INFO - Skip interface_detach for VM ctest-right_vm-36847611 in state BUILD
2025-11-10 02:58:14,453 - INFO - Deleting VM ctest-right_vm-36847611
2025-11-10 02:58:14,453 - ERROR - VM ctest-right_vm-36847611 has failed to come up
2025-11-10 02:58:14,453 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:14,453 - ERROR - VM ctest-right_vm-36847611 has failed to come up
2025-11-10 02:58:14,453 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:14,555 - INFO - Skip interface_detach for VM ctest-left_vm-16439267 in state BUILD
2025-11-10 02:58:14,555 - INFO - Deleting VM ctest-left_vm-16439267
2025-11-10 02:58:14,555 - ERROR - VM ctest-left_vm-16439267 has failed to come up
2025-11-10 02:58:14,555 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:14,555 - ERROR - VM ctest-left_vm-16439267 has failed to come up
2025-11-10 02:58:14,555 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:14,654 - INFO - Deleting VN ctest-trans_right_vn-27497126
2025-11-10 02:58:14,857 - DEBUG - Response for deleting network ()
2025-11-10 02:58:14,857 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/219d3ecd-fee1-4cbe-85a6-7489113256b9
2025-11-10 02:58:14,865 - DEBUG - Response Code: 404
2025-11-10 02:58:14,865 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:14,871 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:14,939 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:15,049 - INFO - Validated that VN ctest-trans_right_vn-27497126 is not found in API Server
2025-11-10 02:58:15,070 - DEBUG - VN ctest-trans_right_vn-27497126 is not present in Agent 10.0.0.65
2025-11-10 02:58:15,102 - DEBUG - VN ctest-trans_right_vn-27497126 is not present in Agent 10.0.0.130
2025-11-10 02:58:15,102 - INFO - Validated that VN ctest-trans_right_vn-27497126 is not in any agent
2025-11-10 02:58:15,138 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-27497126 info
2025-11-10 02:58:15,139 - INFO - Deleting VN ctest-trans_left_vn-03900649
2025-11-10 02:58:15,323 - DEBUG - Response for deleting network ()
2025-11-10 02:58:15,323 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6b5c7ade-9d62-4e15-bc50-33477cd8f540
2025-11-10 02:58:15,335 - DEBUG - Response Code: 404
2025-11-10 02:58:15,335 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:15,354 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:15,378 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:15,482 - INFO - Validated that VN ctest-trans_left_vn-03900649 is not found in API Server
2025-11-10 02:58:15,500 - DEBUG - VN ctest-trans_left_vn-03900649 is not present in Agent 10.0.0.65
2025-11-10 02:58:15,513 - DEBUG - VN ctest-trans_left_vn-03900649 is not present in Agent 10.0.0.130
2025-11-10 02:58:15,513 - INFO - Validated that VN ctest-trans_left_vn-03900649 is not in any agent
2025-11-10 02:58:15,558 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-03900649 info
2025-11-10 02:58:15,559 - INFO - Deleting VN ctest-right_vn-56421494
2025-11-10 02:58:15,605 - DEBUG - VN c5aaa4df-24d3-4425-9f34-869d0893ca66 still in use: Unable to complete operation on network c5aaa4df-24d3-4425-9f34-869d0893ca66. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-40a43026-60d8-4c66-a92e-d644460bc55a']
2025-11-10 02:58:15,605 - WARNING - Deleting VN ctest-right_vn-56421494 failed..Will retry
2025-11-10 02:58:17,774 - DEBUG - Response for deleting network ()
2025-11-10 02:58:17,774 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/54c4494b-65c5-4da1-a560-8eff9d2cbeae
2025-11-10 02:58:17,780 - DEBUG - Response Code: 404
2025-11-10 02:58:17,780 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:17,787 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:17,817 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:17,920 - INFO - Validated that VN ctest-right_vn-56421494 is not found in API Server
2025-11-10 02:58:17,933 - DEBUG - VN ctest-right_vn-56421494 is not present in Agent 10.0.0.65
2025-11-10 02:58:17,950 - DEBUG - VN ctest-right_vn-56421494 is not present in Agent 10.0.0.130
2025-11-10 02:58:17,950 - INFO - Validated that VN ctest-right_vn-56421494 is not in any agent
2025-11-10 02:58:17,985 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-56421494 info
2025-11-10 02:58:17,985 - INFO - Deleting VN ctest-left_vn-70355567
2025-11-10 02:58:18,152 - DEBUG - Response for deleting network ()
2025-11-10 02:58:18,152 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/c0ef5f16-0159-48a5-8f9e-2570dfeb57a6
2025-11-10 02:58:18,159 - DEBUG - Response Code: 404
2025-11-10 02:58:18,159 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:18,167 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:18,232 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:18,340 - INFO - Validated that VN ctest-left_vn-70355567 is not found in API Server
2025-11-10 02:58:18,352 - DEBUG - VN ctest-left_vn-70355567 is not present in Agent 10.0.0.65
2025-11-10 02:58:18,366 - DEBUG - VN ctest-left_vn-70355567 is not present in Agent 10.0.0.130
2025-11-10 02:58:18,366 - INFO - Validated that VN ctest-left_vn-70355567 is not in any agent
2025-11-10 02:58:18,410 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-70355567 info
2025-11-10 02:58:18,410 - INFO - Deleting VN ctest-mgmt_vn-87570736
2025-11-10 02:58:18,576 - DEBUG - Response for deleting network ()
2025-11-10 02:58:18,576 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d54595f5-d103-49fc-b830-1ca2ce0a30fb
2025-11-10 02:58:18,582 - DEBUG - Response Code: 404
2025-11-10 02:58:18,582 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:18,588 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:18,624 - DEBUG - Requesting: http://10.0.0.22:8082/project/ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:18,769 - INFO - Validated that VN ctest-mgmt_vn-87570736 is not found in API Server
2025-11-10 02:58:18,782 - DEBUG - VN ctest-mgmt_vn-87570736 is not present in Agent 10.0.0.65
2025-11-10 02:58:18,793 - DEBUG - VN ctest-mgmt_vn-87570736 is not present in Agent 10.0.0.130
2025-11-10 02:58:18,793 - INFO - Validated that VN ctest-mgmt_vn-87570736 is not in any agent
2025-11-10 02:58:18,841 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-87570736 info
2025-11-10 02:58:20,169 - DEBUG - No XMPP flaps were noticed during the test
2025-11-10 02:58:20,170 - INFO -
2025-11-10 02:58:20,170 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-11-10 02:58:20,170 - INFO - --------------------------------------------------------------------------------
2025-11-10 02:58:20,925 - INFO - Deleted project: ctest-TestSvcRegr-47574789, ID : ac8a00e2-ecbe-4252-9810-14750bee4f7a
2025-11-10 02:58:21,138 - INFO - Domain Default found not creating
2025-11-10 02:58:21,304 - INFO - Project ctest-TestSvcRegr-62114407 not found, creating it
2025-11-10 02:58:21,872 - INFO - Created Project:ctest-TestSvcRegr-62114407, ID : 25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:23,405 - INFO - ================================================================================
2025-11-10 02:58:23,405 - INFO - STARTING TEST : test_svc_CEM_22032
2025-11-10 02:58:24,668 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-11-10 02:58:24,669 - INFO - Initial checks done. Running the testcase now
2025-11-10 02:58:24,669 - INFO -
2025-11-10 02:58:25,343 - DEBUG - Response for create_network : {'network': {'id': 'e9699adb-80f1-4b66-90ee-e02bc55a9c3f', 'name': 'ctest-mgmt_vn-01939304', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'ctest-mgmt_vn-01939304'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:25,623 - DEBUG - Response for create_subnet : {'subnet': {'id': '676beac1-85b9-4d61-8723-047e1f5cfb9d', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': 'e9699adb-80f1-4b66-90ee-e02bc55a9c3f', 'ip_version': 4, 'cidr': '160.103.14.128/26', 'allocation_pools': [{'start': '160.103.14.130', 'end': '160.103.14.190'}], 'gateway_ip': '160.103.14.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.103.14.130', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:25,645 - INFO - Created VN ctest-mgmt_vn-01939304
2025-11-10 02:58:25,658 - DEBUG - VN ctest-mgmt_vn-01939304 UUID is e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:25,738 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:26,048 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:26,078 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:26,108 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:26,215 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,228 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,239 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:26,251 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:26,261 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/727480c5-76b8-4359-8339-bbac82e655a2
2025-11-10 02:58:26,271 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:26,271 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,281 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:26,297 - INFO - Verified VN network id 15 for VN e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,298 - INFO - Verifications in API Server for VN ctest-mgmt_vn-01939304 passed
2025-11-10 02:58:26,298 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,309 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:26,317 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:26,325 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/727480c5-76b8-4359-8339-bbac82e655a2
2025-11-10 02:58:26,339 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16819144550943640422', 'uuid-lslong': '10443531064518024255'}, 'enable': 'true', 'created': '2025-11-10T02:58:25', 'last-modified': '2025-11-10T02:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.771875'}
2025-11-10 02:58:26,350 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:26,356 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16819144550943640422', 'uuid-lslong': '10443531064518024255'}, 'enable': 'true', 'created': '2025-11-10T02:58:25', 'last-modified': '2025-11-10T02:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.789874'}
2025-11-10 02:58:26,362 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:26,369 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16819144550943640422', 'uuid-lslong': '10443531064518024255'}, 'enable': 'true', 'created': '2025-11-10T02:58:25', 'last-modified': '2025-11-10T02:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.801474'}
2025-11-10 02:58:26,377 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:26,377 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-01939304 passed
2025-11-10 02:58:26,378 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-01939304 in API_Server ======
2025-11-10 02:58:26,378 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:26,388 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:26,401 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:26,505 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/e9699adb-80f1-4b66-90ee-e02bc55a9c3f
2025-11-10 02:58:26,522 - DEBUG - =>VN ctest-mgmt_vn-01939304 has no policy to be verified
2025-11-10 02:58:26,523 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:26,523 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:26,523 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:26,834 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:26,909 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304?flat'}
2025-11-10 02:58:26,909 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:26,909 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:27,205 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:27,277 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304?flat'}
2025-11-10 02:58:27,277 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:27,277 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:27,579 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:27,638 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304?flat'}
2025-11-10 02:58:27,638 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-01939304 is found in opserver
2025-11-10 02:58:27,659 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:27,668 - DEBUG - VRF ids for VN ctest-mgmt_vn-01939304: {}
2025-11-10 02:58:28,031 - DEBUG - Response for create_network : {'network': {'id': '221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2', 'name': 'service-left-vn-26969069', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'service-left-vn-26969069'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:28,422 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bde7b1eb-9817-4baf-bef0-ecff370b4026', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': '221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2', 'ip_version': 4, 'cidr': '124.50.35.192/26', 'allocation_pools': [{'start': '124.50.35.194', 'end': '124.50.35.254'}], 'gateway_ip': '124.50.35.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '124.50.35.194', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:28,445 - INFO - Created VN service-left-vn-26969069
2025-11-10 02:58:28,458 - DEBUG - VN service-left-vn-26969069 UUID is 221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,537 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:28,547 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:28,570 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:28,684 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,699 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,709 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:28,719 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:28,729 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d077596f-46ee-40ad-ae15-c338a24e9c92
2025-11-10 02:58:28,738 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:28,738 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,746 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:28,766 - INFO - Verified VN network id 16 for VN 221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,766 - INFO - Verifications in API Server for VN service-left-vn-26969069 passed
2025-11-10 02:58:28,766 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,778 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:28,785 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:28,792 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d077596f-46ee-40ad-ae15-c338a24e9c92
2025-11-10 02:58:28,803 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2458598884445407458', 'uuid-lslong': '10368806849287542994'}, 'enable': 'true', 'created': '2025-11-10T02:58:27', 'last-modified': '2025-11-10T02:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458504'}
2025-11-10 02:58:28,809 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:28,815 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2458598884445407458', 'uuid-lslong': '10368806849287542994'}, 'enable': 'true', 'created': '2025-11-10T02:58:27', 'last-modified': '2025-11-10T02:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471752'}
2025-11-10 02:58:28,824 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:28,830 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2458598884445407458', 'uuid-lslong': '10368806849287542994'}, 'enable': 'true', 'created': '2025-11-10T02:58:27', 'last-modified': '2025-11-10T02:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.485216'}
2025-11-10 02:58:28,836 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:28,836 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-26969069 passed
2025-11-10 02:58:28,836 - DEBUG - ====Verifying policy data for service-left-vn-26969069 in API_Server ======
2025-11-10 02:58:28,837 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:28,843 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:28,854 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:28,964 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/221eb2a8-c08a-48e2-8fe5-66e4a46bd8d2
2025-11-10 02:58:28,973 - DEBUG - =>VN service-left-vn-26969069 has no policy to be verified
2025-11-10 02:58:28,974 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:28,974 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:28,974 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:29,036 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069?flat'}
2025-11-10 02:58:29,036 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:29,036 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:29,096 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069?flat'}
2025-11-10 02:58:29,097 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:29,097 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:29,159 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069?flat'}
2025-11-10 02:58:29,159 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:service-left-vn-26969069 is found in opserver
2025-11-10 02:58:29,181 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:29,193 - DEBUG - VRF ids for VN service-left-vn-26969069: {}
2025-11-10 02:58:29,525 - DEBUG - Response for create_network : {'network': {'id': 'f1e7af98-f710-4fd6-976b-a4a5103a5d63', 'name': 'service-right-vn-32137699', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'service-right-vn-32137699'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:29,803 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea2cf513-3363-4d6d-bd3d-5d2909202286', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': 'f1e7af98-f710-4fd6-976b-a4a5103a5d63', 'ip_version': 4, 'cidr': '11.131.47.192/26', 'allocation_pools': [{'start': '11.131.47.194', 'end': '11.131.47.254'}], 'gateway_ip': '11.131.47.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.131.47.194', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:29,831 - INFO - Created VN service-right-vn-32137699
2025-11-10 02:58:29,843 - DEBUG - VN service-right-vn-32137699 UUID is f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:29,935 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:29,943 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:29,955 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:30,052 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,067 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,077 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:30,090 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:30,097 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7167c31a-9b63-41c2-8451-9a62406c7a5f
2025-11-10 02:58:30,105 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:30,105 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,116 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:30,139 - INFO - Verified VN network id 17 for VN f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,139 - INFO - Verifications in API Server for VN service-right-vn-32137699 passed
2025-11-10 02:58:30,139 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,149 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:30,158 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:30,167 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/7167c31a-9b63-41c2-8451-9a62406c7a5f
2025-11-10 02:58:30,182 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17431093954275725270', 'uuid-lslong': '10910995551083978083'}, 'enable': 'true', 'created': '2025-11-10T02:58:29', 'last-modified': '2025-11-10T02:58:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437771'}
2025-11-10 02:58:30,189 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:30,198 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17431093954275725270', 'uuid-lslong': '10910995551083978083'}, 'enable': 'true', 'created': '2025-11-10T02:58:29', 'last-modified': '2025-11-10T02:58:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454996'}
2025-11-10 02:58:30,204 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:30,213 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17431093954275725270', 'uuid-lslong': '10910995551083978083'}, 'enable': 'true', 'created': '2025-11-10T02:58:29', 'last-modified': '2025-11-10T02:58:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474041'}
2025-11-10 02:58:30,221 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:30,221 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-32137699 passed
2025-11-10 02:58:30,221 - DEBUG - ====Verifying policy data for service-right-vn-32137699 in API_Server ======
2025-11-10 02:58:30,221 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:30,227 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:30,241 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:30,341 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/f1e7af98-f710-4fd6-976b-a4a5103a5d63
2025-11-10 02:58:30,354 - DEBUG - =>VN service-right-vn-32137699 has no policy to be verified
2025-11-10 02:58:30,354 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:30,354 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:30,354 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:30,419 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699?flat'}
2025-11-10 02:58:30,419 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:30,420 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:30,484 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699?flat'}
2025-11-10 02:58:30,484 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:30,484 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:30,544 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699?flat'}
2025-11-10 02:58:30,544 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:service-right-vn-32137699 is found in opserver
2025-11-10 02:58:30,576 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:30,601 - DEBUG - VRF ids for VN service-right-vn-32137699: {}
2025-11-10 02:58:30,666 - DEBUG - Services list from nova: [, , , ]
2025-11-10 02:58:31,741 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1)
2025-11-10 02:58:33,154 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2)
2025-11-10 02:58:33,154 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-87499902']
2025-11-10 02:58:33,485 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-87499902'] created succesfully
2025-11-10 02:58:33,921 - DEBUG - Image by name tiny_nat_fw not found
2025-11-10 02:58:33,922 - DEBUG - Installing image tiny_nat_fw
2025-11-10 02:58:33,922 - 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-11-10 02:58:33,922 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:33,922 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:33,922 - DEBUG - Unzip image
2025-11-10 02:58:33,922 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:33,944 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-10 02:58:33,944 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-87499902']
2025-11-10 02:58:34,049 - INFO - Service template: ['default-domain', 'ctest-service_template_1-87499902'] deleted successfully.
2025-11-10 02:58:34,049 - INFO - Skip interface_detach for VM ctest-right_vm-70349163 in state BUILD
2025-11-10 02:58:34,049 - INFO - Deleting VM ctest-right_vm-70349163
2025-11-10 02:58:34,095 - ERROR - VM ctest-right_vm-70349163 has failed to come up
2025-11-10 02:58:34,095 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:34,095 - ERROR - VM ctest-right_vm-70349163 has failed to come up
2025-11-10 02:58:34,095 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:34,207 - INFO - Skip interface_detach for VM ctest-left_vm-56125641 in state BUILD
2025-11-10 02:58:34,207 - INFO - Deleting VM ctest-left_vm-56125641
2025-11-10 02:58:34,208 - ERROR - VM ctest-left_vm-56125641 has failed to come up
2025-11-10 02:58:34,208 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:34,208 - ERROR - VM ctest-left_vm-56125641 has failed to come up
2025-11-10 02:58:34,208 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:34,287 - INFO - Deleting VN service-right-vn-32137699
2025-11-10 02:58:34,334 - DEBUG - VN f1e7af98-f710-4fd6-976b-a4a5103a5d63 still in use: Unable to complete operation on network f1e7af98-f710-4fd6-976b-a4a5103a5d63. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2d5deef6-9b3f-488b-a633-23921858d831']
2025-11-10 02:58:34,334 - WARNING - Deleting VN service-right-vn-32137699 failed..Will retry
2025-11-10 02:58:36,540 - DEBUG - Response for deleting network ()
2025-11-10 02:58:36,540 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/97abd188-eaab-485b-a400-713e92c8eb40
2025-11-10 02:58:36,552 - DEBUG - Response Code: 404
2025-11-10 02:58:36,553 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:36,561 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:36,585 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:36,702 - INFO - Validated that VN service-right-vn-32137699 is not found in API Server
2025-11-10 02:58:36,715 - DEBUG - VN service-right-vn-32137699 is not present in Agent 10.0.0.65
2025-11-10 02:58:36,730 - DEBUG - VN service-right-vn-32137699 is not present in Agent 10.0.0.130
2025-11-10 02:58:36,730 - INFO - Validated that VN service-right-vn-32137699 is not in any agent
2025-11-10 02:58:36,785 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-32137699 info
2025-11-10 02:58:36,786 - INFO - Deleting VN service-left-vn-26969069
2025-11-10 02:58:36,995 - DEBUG - Response for deleting network ()
2025-11-10 02:58:36,995 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/ab3ff1a7-2c6f-4b49-8bc3-f66ed607801d
2025-11-10 02:58:37,001 - DEBUG - Response Code: 404
2025-11-10 02:58:37,002 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:37,021 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:37,047 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:37,167 - INFO - Validated that VN service-left-vn-26969069 is not found in API Server
2025-11-10 02:58:37,178 - DEBUG - VN service-left-vn-26969069 is not present in Agent 10.0.0.65
2025-11-10 02:58:37,192 - DEBUG - VN service-left-vn-26969069 is not present in Agent 10.0.0.130
2025-11-10 02:58:37,193 - INFO - Validated that VN service-left-vn-26969069 is not in any agent
2025-11-10 02:58:37,248 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-26969069 info
2025-11-10 02:58:37,249 - INFO - Deleting VN ctest-mgmt_vn-01939304
2025-11-10 02:58:37,432 - DEBUG - Response for deleting network ()
2025-11-10 02:58:37,432 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/1d681b9a-f6c9-456c-8169-da158239a316
2025-11-10 02:58:37,439 - DEBUG - Response Code: 404
2025-11-10 02:58:37,439 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:37,449 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:37,472 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:37,576 - INFO - Validated that VN ctest-mgmt_vn-01939304 is not found in API Server
2025-11-10 02:58:37,589 - DEBUG - VN ctest-mgmt_vn-01939304 is not present in Agent 10.0.0.65
2025-11-10 02:58:37,600 - DEBUG - VN ctest-mgmt_vn-01939304 is not present in Agent 10.0.0.130
2025-11-10 02:58:37,600 - INFO - Validated that VN ctest-mgmt_vn-01939304 is not in any agent
2025-11-10 02:58:37,647 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-01939304 info
2025-11-10 02:58:38,946 - DEBUG - No XMPP flaps were noticed during the test
2025-11-10 02:58:38,946 - INFO -
2025-11-10 02:58:38,946 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-11-10 02:58:38,946 - INFO - --------------------------------------------------------------------------------
2025-11-10 02:58:38,952 - INFO - ================================================================================
2025-11-10 02:58:38,952 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-11-10 02:58:40,224 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-11-10 02:58:40,224 - INFO - Initial checks done. Running the testcase now
2025-11-10 02:58:40,224 - INFO -
2025-11-10 02:58:40,508 - DEBUG - Response for create_network : {'network': {'id': 'edb71bb0-c731-4f4d-a33d-ddaca6326d68', 'name': 'ctest-mgmt_vn-92783415', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'ctest-mgmt_vn-92783415'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:40,747 - DEBUG - Response for create_subnet : {'subnet': {'id': '0d3fd959-4ba8-45d9-82d9-bcf1ade2fb8f', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': 'edb71bb0-c731-4f4d-a33d-ddaca6326d68', 'ip_version': 4, 'cidr': '160.5.155.192/26', 'allocation_pools': [{'start': '160.5.155.194', 'end': '160.5.155.254'}], 'gateway_ip': '160.5.155.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.5.155.194', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:40,772 - INFO - Created VN ctest-mgmt_vn-92783415
2025-11-10 02:58:40,787 - DEBUG - VN ctest-mgmt_vn-92783415 UUID is edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:40,869 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:40,879 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:40,891 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:40,993 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,019 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,029 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:41,040 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:41,050 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f3f54874-32fa-4f0e-bf0b-029cfdede83e
2025-11-10 02:58:41,060 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:41,060 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,070 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:41,091 - INFO - Verified VN network id 15 for VN edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,092 - INFO - Verifications in API Server for VN ctest-mgmt_vn-92783415 passed
2025-11-10 02:58:41,092 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,107 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:41,122 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:41,134 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/f3f54874-32fa-4f0e-bf0b-029cfdede83e
2025-11-10 02:58:41,152 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17129190153797062477', 'uuid-lslong': '11762801535354039656'}, 'enable': 'true', 'created': '2025-11-10T02:58:40', 'last-modified': '2025-11-10T02:58:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468646'}
2025-11-10 02:58:41,159 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:41,166 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17129190153797062477', 'uuid-lslong': '11762801535354039656'}, 'enable': 'true', 'created': '2025-11-10T02:58:40', 'last-modified': '2025-11-10T02:58:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.485469'}
2025-11-10 02:58:41,172 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:41,178 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17129190153797062477', 'uuid-lslong': '11762801535354039656'}, 'enable': 'true', 'created': '2025-11-10T02:58:40', 'last-modified': '2025-11-10T02:58:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497473'}
2025-11-10 02:58:41,185 - DEBUG - Route Targets: ['target:64512:8000004']
2025-11-10 02:58:41,185 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-92783415 passed
2025-11-10 02:58:41,185 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-92783415 in API_Server ======
2025-11-10 02:58:41,185 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:41,192 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:41,205 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:41,309 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/edb71bb0-c731-4f4d-a33d-ddaca6326d68
2025-11-10 02:58:41,318 - DEBUG - =>VN ctest-mgmt_vn-92783415 has no policy to be verified
2025-11-10 02:58:41,318 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:41,318 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:41,318 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:41,330 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415?flat'}
2025-11-10 02:58:41,331 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:41,331 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:41,344 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415?flat'}
2025-11-10 02:58:41,344 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:41,344 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:41,359 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415?flat'}
2025-11-10 02:58:41,359 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:ctest-mgmt_vn-92783415 is found in opserver
2025-11-10 02:58:41,383 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:41,395 - DEBUG - VRF ids for VN ctest-mgmt_vn-92783415: {}
2025-11-10 02:58:41,712 - DEBUG - Response for create_network : {'network': {'id': '14a9e004-56df-4ffc-988d-77fb6c759326', 'name': 'ctest-left_vn-81152602', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'ctest-left_vn-81152602'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:41,927 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e7dd6f36-ee40-45c4-9fb9-643875e2fe37', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': '14a9e004-56df-4ffc-988d-77fb6c759326', 'ip_version': 4, 'cidr': '84.79.29.192/26', 'allocation_pools': [{'start': '84.79.29.194', 'end': '84.79.29.254'}], 'gateway_ip': '84.79.29.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '84.79.29.194', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:41,953 - INFO - Created VN ctest-left_vn-81152602
2025-11-10 02:58:41,967 - DEBUG - VN ctest-left_vn-81152602 UUID is 14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,044 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:42,052 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:42,067 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:42,165 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,177 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,186 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:42,193 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:42,201 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/dc1f4c19-0bf6-401d-b655-bfe981048d78
2025-11-10 02:58:42,208 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:42,209 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,219 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:42,236 - INFO - Verified VN network id 16 for VN 14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,236 - INFO - Verifications in API Server for VN ctest-left_vn-81152602 passed
2025-11-10 02:58:42,236 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,246 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:42,255 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:42,262 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/dc1f4c19-0bf6-401d-b655-bfe981048d78
2025-11-10 02:58:42,277 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1488967461064626172', 'uuid-lslong': '10992574187221390118'}, 'enable': 'true', 'created': '2025-11-10T02:58:41', 'last-modified': '2025-11-10T02:58:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399244'}
2025-11-10 02:58:42,283 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:42,289 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1488967461064626172', 'uuid-lslong': '10992574187221390118'}, 'enable': 'true', 'created': '2025-11-10T02:58:41', 'last-modified': '2025-11-10T02:58:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410711'}
2025-11-10 02:58:42,296 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:42,304 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1488967461064626172', 'uuid-lslong': '10992574187221390118'}, 'enable': 'true', 'created': '2025-11-10T02:58:41', 'last-modified': '2025-11-10T02:58:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422684'}
2025-11-10 02:58:42,311 - DEBUG - Route Targets: ['target:64512:8000006']
2025-11-10 02:58:42,311 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-81152602 passed
2025-11-10 02:58:42,312 - DEBUG - ====Verifying policy data for ctest-left_vn-81152602 in API_Server ======
2025-11-10 02:58:42,312 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:42,318 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:42,331 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:42,426 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/14a9e004-56df-4ffc-988d-77fb6c759326
2025-11-10 02:58:42,438 - DEBUG - =>VN ctest-left_vn-81152602 has no policy to be verified
2025-11-10 02:58:42,438 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:42,438 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:42,438 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:42,451 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602?flat'}
2025-11-10 02:58:42,451 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:42,451 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:42,465 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602?flat'}
2025-11-10 02:58:42,465 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:42,465 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:42,480 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602?flat'}
2025-11-10 02:58:42,480 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:ctest-left_vn-81152602 is found in opserver
2025-11-10 02:58:42,501 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:42,512 - DEBUG - VRF ids for VN ctest-left_vn-81152602: {}
2025-11-10 02:58:42,814 - DEBUG - Response for create_network : {'network': {'id': 'fc28c157-ca4c-4878-b346-60b00b643197', 'name': 'ctest-right_vn-43331123', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'project_id': '25161f948c884387a4f8709bf3e97b3a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62114407', 'ctest-right_vn-43331123'], 'port_security_enabled': True, 'description': ''}}
2025-11-10 02:58:43,042 - DEBUG - Response for create_subnet : {'subnet': {'id': '10b60bae-d8ca-4a0c-989c-6f7e32a9766c', 'name': '', 'tenant_id': '25161f948c884387a4f8709bf3e97b3a', 'network_id': 'fc28c157-ca4c-4878-b346-60b00b643197', 'ip_version': 4, 'cidr': '96.14.101.192/26', 'allocation_pools': [{'start': '96.14.101.194', 'end': '96.14.101.254'}], 'gateway_ip': '96.14.101.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.14.101.194', 'tags': [], 'project_id': '25161f948c884387a4f8709bf3e97b3a'}}
2025-11-10 02:58:43,068 - INFO - Created VN ctest-right_vn-43331123
2025-11-10 02:58:43,082 - DEBUG - VN ctest-right_vn-43331123 UUID is fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,159 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:43,166 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:43,178 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:43,288 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,308 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,323 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:43,332 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:43,342 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/04dcd8b2-bd61-4265-820f-f34bb573ee29
2025-11-10 02:58:43,351 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:43,352 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,366 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:43,386 - INFO - Verified VN network id 17 for VN fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,386 - INFO - Verifications in API Server for VN ctest-right_vn-43331123 passed
2025-11-10 02:58:43,386 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,396 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:43,405 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:43,412 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/04dcd8b2-bd61-4265-820f-f34bb573ee29
2025-11-10 02:58:43,433 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18169985279426578552', 'uuid-lslong': '12918118890380472727'}, 'enable': 'true', 'created': '2025-11-10T02:58:42', 'last-modified': '2025-11-10T02:58:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446848'}
2025-11-10 02:58:43,441 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:43,447 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18169985279426578552', 'uuid-lslong': '12918118890380472727'}, 'enable': 'true', 'created': '2025-11-10T02:58:42', 'last-modified': '2025-11-10T02:58:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454799'}
2025-11-10 02:58:43,455 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:43,461 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62114407', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18169985279426578552', 'uuid-lslong': '12918118890380472727'}, 'enable': 'true', 'created': '2025-11-10T02:58:42', 'last-modified': '2025-11-10T02:58:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476926'}
2025-11-10 02:58:43,468 - DEBUG - Route Targets: ['target:64512:8000007']
2025-11-10 02:58:43,468 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-43331123 passed
2025-11-10 02:58:43,468 - DEBUG - ====Verifying policy data for ctest-right_vn-43331123 in API_Server ======
2025-11-10 02:58:43,468 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:43,475 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:43,486 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:43,584 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/fc28c157-ca4c-4878-b346-60b00b643197
2025-11-10 02:58:43,603 - DEBUG - =>VN ctest-right_vn-43331123 has no policy to be verified
2025-11-10 02:58:43,603 - DEBUG - Verifying the vn in opserver
2025-11-10 02:58:43,603 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123 virtual network link through opserver 10.0.0.22
2025-11-10 02:58:43,603 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-11-10 02:58:43,617 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123?flat'}
2025-11-10 02:58:43,617 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123 virtual network link through opserver 10.0.0.50
2025-11-10 02:58:43,617 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-11-10 02:58:43,632 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123?flat'}
2025-11-10 02:58:43,632 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123 virtual network link through opserver 10.0.0.23
2025-11-10 02:58:43,632 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2025-11-10 02:58:43,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123?flat'}
2025-11-10 02:58:43,648 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62114407:ctest-right_vn-43331123 is found in opserver
2025-11-10 02:58:43,669 - DEBUG - Do not have enough data to verify VN in agent
2025-11-10 02:58:43,684 - DEBUG - VRF ids for VN ctest-right_vn-43331123: {}
2025-11-10 02:58:44,821 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-2)
2025-11-10 02:58:46,173 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4674-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4674-1)
2025-11-10 02:58:46,173 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-03167476']
2025-11-10 02:58:46,485 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-03167476'] created succesfully
2025-11-10 02:58:46,830 - DEBUG - Image by name tiny_nat_fw not found
2025-11-10 02:58:46,830 - DEBUG - Installing image tiny_nat_fw
2025-11-10 02:58:46,830 - 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-11-10 02:58:46,830 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:46,831 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:46,831 - DEBUG - Unzip image
2025-11-10 02:58:46,831 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-11-10 02:58:46,869 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-11-10 02:58:46,869 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-03167476']
2025-11-10 02:58:46,976 - INFO - Service template: ['default-domain', 'ctest-service_template_1-03167476'] deleted successfully.
2025-11-10 02:58:46,977 - INFO - Skip interface_detach for VM ctest-right_vm-07644218 in state BUILD
2025-11-10 02:58:46,977 - INFO - Deleting VM ctest-right_vm-07644218
2025-11-10 02:58:46,977 - ERROR - VM ctest-right_vm-07644218 has failed to come up
2025-11-10 02:58:46,977 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:46,977 - ERROR - VM ctest-right_vm-07644218 has failed to come up
2025-11-10 02:58:46,977 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:47,102 - INFO - Skip interface_detach for VM ctest-left_vm-19668784 in state BUILD
2025-11-10 02:58:47,102 - INFO - Deleting VM ctest-left_vm-19668784
2025-11-10 02:58:47,102 - ERROR - VM ctest-left_vm-19668784 has failed to come up
2025-11-10 02:58:47,102 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:47,102 - ERROR - VM ctest-left_vm-19668784 has failed to come up
2025-11-10 02:58:47,102 - ERROR - Nova failed to get host of the VM
2025-11-10 02:58:47,174 - INFO - Deleting VN ctest-right_vn-43331123
2025-11-10 02:58:47,227 - DEBUG - VN fc28c157-ca4c-4878-b346-60b00b643197 still in use: Unable to complete operation on network fc28c157-ca4c-4878-b346-60b00b643197. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4803061e-a69b-427f-8683-bec933bc4371']
2025-11-10 02:58:47,227 - WARNING - Deleting VN ctest-right_vn-43331123 failed..Will retry
2025-11-10 02:58:49,400 - DEBUG - Response for deleting network ()
2025-11-10 02:58:49,400 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/3d8d2871-87cf-4b19-8264-c534e7f0fba6
2025-11-10 02:58:49,409 - DEBUG - Response Code: 404
2025-11-10 02:58:49,409 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:49,415 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:49,443 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:49,538 - INFO - Validated that VN ctest-right_vn-43331123 is not found in API Server
2025-11-10 02:58:49,550 - DEBUG - VN ctest-right_vn-43331123 is not present in Agent 10.0.0.65
2025-11-10 02:58:49,567 - DEBUG - VN ctest-right_vn-43331123 is not present in Agent 10.0.0.130
2025-11-10 02:58:49,567 - INFO - Validated that VN ctest-right_vn-43331123 is not in any agent
2025-11-10 02:58:49,601 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-43331123 info
2025-11-10 02:58:49,601 - INFO - Deleting VN ctest-left_vn-81152602
2025-11-10 02:58:49,767 - DEBUG - Response for deleting network ()
2025-11-10 02:58:49,767 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e7c936bb-cd2f-4a00-a7fe-6da2f42a019a
2025-11-10 02:58:49,776 - DEBUG - Response Code: 404
2025-11-10 02:58:49,776 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:49,783 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:49,799 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:49,888 - INFO - Validated that VN ctest-left_vn-81152602 is not found in API Server
2025-11-10 02:58:49,900 - DEBUG - VN ctest-left_vn-81152602 is not present in Agent 10.0.0.65
2025-11-10 02:58:49,911 - DEBUG - VN ctest-left_vn-81152602 is not present in Agent 10.0.0.130
2025-11-10 02:58:49,911 - INFO - Validated that VN ctest-left_vn-81152602 is not in any agent
2025-11-10 02:58:49,948 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-81152602 info
2025-11-10 02:58:49,948 - INFO - Deleting VN ctest-mgmt_vn-92783415
2025-11-10 02:58:50,113 - DEBUG - Response for deleting network ()
2025-11-10 02:58:50,113 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9a72fa57-ab21-4e6c-ae0a-db3bed40e714
2025-11-10 02:58:50,119 - DEBUG - Response Code: 404
2025-11-10 02:58:50,120 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-11-10 02:58:50,125 - DEBUG - Requesting: http://10.0.0.22:8082/domain/dbdabfa7-f87c-4117-b05b-68cc6fd252ce
2025-11-10 02:58:50,195 - DEBUG - Requesting: http://10.0.0.22:8082/project/25161f94-8c88-4387-a4f8-709bf3e97b3a
2025-11-10 02:58:50,298 - INFO - Validated that VN ctest-mgmt_vn-92783415 is not found in API Server
2025-11-10 02:58:50,308 - DEBUG - VN ctest-mgmt_vn-92783415 is not present in Agent 10.0.0.65
2025-11-10 02:58:50,320 - DEBUG - VN ctest-mgmt_vn-92783415 is not present in Agent 10.0.0.130
2025-11-10 02:58:50,320 - INFO - Validated that VN ctest-mgmt_vn-92783415 is not in any agent
2025-11-10 02:58:50,354 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-92783415 info
2025-11-10 02:58:51,646 - DEBUG - No XMPP flaps were noticed during the test
2025-11-10 02:58:51,646 - INFO -
2025-11-10 02:58:51,646 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-11-10 02:58:51,646 - INFO - --------------------------------------------------------------------------------
2025-11-10 02:58:52,476 - INFO - Deleted project: ctest-TestSvcRegr-62114407, ID : 25161f94-8c88-4387-a4f8-709bf3e97b3a