]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:07:06,384 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-62408959']
2025-10-20 03:07:06,690 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-62408959'] created succesfully
2025-10-20 03:07:07,058 - DEBUG - Image by name tiny_nat_fw not found
2025-10-20 03:07:07,058 - DEBUG - Installing image tiny_nat_fw
2025-10-20 03:07:07,058 - 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-10-20 03:07:07,058 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:07,058 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:07,079 - 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-10-20 03:07:07,136 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:07,136 - DEBUG - Unzip image
2025-10-20 03:07:07,136 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:07,156 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-20 03:07:07,156 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-62408959']
2025-10-20 03:07:07,253 - INFO - Service template: ['default-domain', 'ctest-service_template_1-62408959'] deleted successfully.
2025-10-20 03:07:07,253 - INFO - Skip interface_detach for VM ctest-right_vm-18226687 in state BUILD
2025-10-20 03:07:07,253 - INFO - Deleting VM ctest-right_vm-18226687
2025-10-20 03:07:07,300 - ERROR - VM ctest-right_vm-18226687 has failed to come up
2025-10-20 03:07:07,301 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:07,301 - ERROR - VM ctest-right_vm-18226687 has failed to come up
2025-10-20 03:07:07,301 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:07,375 - INFO - Skip interface_detach for VM ctest-left_vm-21601774 in state BUILD
2025-10-20 03:07:07,375 - INFO - Deleting VM ctest-left_vm-21601774
2025-10-20 03:07:07,375 - ERROR - VM ctest-left_vm-21601774 has failed to come up
2025-10-20 03:07:07,375 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:07,375 - ERROR - VM ctest-left_vm-21601774 has failed to come up
2025-10-20 03:07:07,375 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:07,439 - INFO - Deleting VN ctest-right_vn-48808375
2025-10-20 03:07:07,477 - DEBUG - VN a21ab6d2-1c72-400c-888f-761c4cec6f2b still in use: Unable to complete operation on network a21ab6d2-1c72-400c-888f-761c4cec6f2b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-de7640f9-4d73-4fe0-8332-26d38af91caf']
2025-10-20 03:07:07,477 - WARNING - Deleting VN ctest-right_vn-48808375 failed..Will retry
2025-10-20 03:07:09,622 - DEBUG - Response for deleting network ()
2025-10-20 03:07:09,622 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d4b83681-b18c-41f1-99f5-dc32835e2a0a
2025-10-20 03:07:09,628 - DEBUG - Response Code: 404
2025-10-20 03:07:09,628 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:09,634 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:09,656 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:09,779 - INFO - Validated that VN ctest-right_vn-48808375 is not found in API Server
2025-10-20 03:07:09,790 - DEBUG - VN ctest-right_vn-48808375 is not present in Agent 10.0.0.131
2025-10-20 03:07:09,799 - DEBUG - VN ctest-right_vn-48808375 is not present in Agent 10.0.0.19
2025-10-20 03:07:09,799 - INFO - Validated that VN ctest-right_vn-48808375 is not in any agent
2025-10-20 03:07:09,831 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-48808375 info
2025-10-20 03:07:09,831 - INFO - Deleting VN ctest-left_vn-97024987
2025-10-20 03:07:09,992 - DEBUG - Response for deleting network ()
2025-10-20 03:07:09,992 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/d9518ede-fe41-43f9-907d-8d171278b3f8
2025-10-20 03:07:09,999 - DEBUG - Response Code: 404
2025-10-20 03:07:09,999 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:10,005 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:10,024 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:10,108 - INFO - Validated that VN ctest-left_vn-97024987 is not found in API Server
2025-10-20 03:07:10,118 - DEBUG - VN ctest-left_vn-97024987 is not present in Agent 10.0.0.131
2025-10-20 03:07:10,128 - DEBUG - VN ctest-left_vn-97024987 is not present in Agent 10.0.0.19
2025-10-20 03:07:10,128 - INFO - Validated that VN ctest-left_vn-97024987 is not in any agent
2025-10-20 03:07:10,165 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-97024987 info
2025-10-20 03:07:10,165 - INFO - Deleting VN ctest-mgmt_vn-99064126
2025-10-20 03:07:10,331 - DEBUG - Response for deleting network ()
2025-10-20 03:07:10,331 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/091f1186-d16e-4489-b444-fdab1512c1fc
2025-10-20 03:07:10,337 - DEBUG - Response Code: 404
2025-10-20 03:07:10,337 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:10,348 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:10,364 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:10,457 - INFO - Validated that VN ctest-mgmt_vn-99064126 is not found in API Server
2025-10-20 03:07:10,468 - DEBUG - VN ctest-mgmt_vn-99064126 is not present in Agent 10.0.0.131
2025-10-20 03:07:10,478 - DEBUG - VN ctest-mgmt_vn-99064126 is not present in Agent 10.0.0.19
2025-10-20 03:07:10,478 - INFO - Validated that VN ctest-mgmt_vn-99064126 is not in any agent
2025-10-20 03:07:10,507 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99064126 info
2025-10-20 03:07:10,507 - INFO - Deleting VM ctest-vm-in-third-vn-43316172
2025-10-20 03:07:10,570 - INFO - Deleting VN ctest-third-vn-37078038
2025-10-20 03:07:10,610 - DEBUG - VN c9dd8571-0861-418a-9078-0bbf1a029bcc still in use: Unable to complete operation on network c9dd8571-0861-418a-9078-0bbf1a029bcc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c016857d-e5eb-44e3-9a0e-560a8e05857c']
2025-10-20 03:07:10,610 - WARNING - Deleting VN ctest-third-vn-37078038 failed..Will retry
2025-10-20 03:07:12,758 - DEBUG - Response for deleting network ()
2025-10-20 03:07:14,035 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:07:14,035 - INFO -
2025-10-20 03:07:14,035 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-10-20 03:07:14,035 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:07:14,038 - INFO - ================================================================================
2025-10-20 03:07:14,038 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-20 03:07:15,304 - DEBUG - Nothing to compare xmpp stats {'10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.19': {'10.20.0.193': '0', '10.20.0.14': '0'}} with
2025-10-20 03:07:15,304 - INFO - Initial checks done. Running the testcase now
2025-10-20 03:07:15,304 - INFO -
2025-10-20 03:07:15,574 - DEBUG - Response for create_network : {'network': {'id': '779dcf0d-e293-4d16-abb6-002236f6944a', 'name': 'ctest-mgmt_vn-71919888', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'project_id': '73927ea28f96455682aab551e37d47fa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55161050', 'ctest-mgmt_vn-71919888'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:15,833 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b8bc753f-95c4-43eb-856a-7fd70b9916ae', 'name': '', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'network_id': '779dcf0d-e293-4d16-abb6-002236f6944a', 'ip_version': 4, 'cidr': '1.177.116.0/26', 'allocation_pools': [{'start': '1.177.116.2', 'end': '1.177.116.62'}], 'gateway_ip': '1.177.116.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '1.177.116.2', 'tags': [], 'project_id': '73927ea28f96455682aab551e37d47fa'}}
2025-10-20 03:07:15,854 - INFO - Created VN ctest-mgmt_vn-71919888
2025-10-20 03:07:15,866 - DEBUG - VN ctest-mgmt_vn-71919888 UUID is 779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:15,937 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:15,943 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:15,952 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:16,047 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,063 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,072 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:16,081 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:16,088 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/52f9f8f8-eac3-4240-afb9-531be224f73f
2025-10-20 03:07:16,095 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:16,095 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,105 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:16,119 - INFO - Verified VN network id 11 for VN 779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,120 - INFO - Verifications in API Server for VN ctest-mgmt_vn-71919888 passed
2025-10-20 03:07:16,120 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,129 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:16,136 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:16,145 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/52f9f8f8-eac3-4240-afb9-531be224f73f
2025-10-20 03:07:16,157 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8619272920399826198', 'uuid-lslong': '12373077173198033994'}, 'enable': 'true', 'created': '2025-10-20T03:07:15', 'last-modified': '2025-10-20T03:07:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.385783'}
2025-10-20 03:07:16,163 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:16,169 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8619272920399826198', 'uuid-lslong': '12373077173198033994'}, 'enable': 'true', 'created': '2025-10-20T03:07:15', 'last-modified': '2025-10-20T03:07:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397494'}
2025-10-20 03:07:16,175 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:16,180 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8619272920399826198', 'uuid-lslong': '12373077173198033994'}, 'enable': 'true', 'created': '2025-10-20T03:07:15', 'last-modified': '2025-10-20T03:07:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403076'}
2025-10-20 03:07:16,185 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:16,185 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-71919888 passed
2025-10-20 03:07:16,185 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-71919888 in API_Server ======
2025-10-20 03:07:16,185 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:16,191 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:16,201 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:16,302 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/779dcf0d-e293-4d16-abb6-002236f6944a
2025-10-20 03:07:16,311 - DEBUG - =>VN ctest-mgmt_vn-71919888 has no policy to be verified
2025-10-20 03:07:16,312 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:16,312 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:16,312 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:16,328 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888?flat'}
2025-10-20 03:07:16,328 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:16,328 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:16,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888?flat'}
2025-10-20 03:07:16,341 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:16,341 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:16,353 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888?flat'}
2025-10-20 03:07:16,353 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55161050:ctest-mgmt_vn-71919888 is found in opserver
2025-10-20 03:07:16,371 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:16,380 - DEBUG - VRF ids for VN ctest-mgmt_vn-71919888: {}
2025-10-20 03:07:16,674 - DEBUG - Response for create_network : {'network': {'id': '4b5be591-df51-48c0-a937-985841a392f6', 'name': 'ctest-left_vn-27928732', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'project_id': '73927ea28f96455682aab551e37d47fa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55161050', 'ctest-left_vn-27928732'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:16,946 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e732e5f0-7172-40ac-9db6-686cc8a8946a', 'name': '', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'network_id': '4b5be591-df51-48c0-a937-985841a392f6', 'ip_version': 4, 'cidr': '210.12.252.64/26', 'allocation_pools': [{'start': '210.12.252.66', 'end': '210.12.252.126'}], 'gateway_ip': '210.12.252.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '210.12.252.66', 'tags': [], 'project_id': '73927ea28f96455682aab551e37d47fa'}}
2025-10-20 03:07:16,977 - INFO - Created VN ctest-left_vn-27928732
2025-10-20 03:07:16,991 - DEBUG - VN ctest-left_vn-27928732 UUID is 4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,059 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:17,066 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:17,079 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:17,170 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,186 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,196 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:17,204 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:17,211 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d47d762e-65b6-4b97-8cf4-0fdbcce7505d
2025-10-20 03:07:17,218 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:17,218 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,227 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:17,243 - INFO - Verified VN network id 12 for VN 4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,243 - INFO - Verifications in API Server for VN ctest-left_vn-27928732 passed
2025-10-20 03:07:17,243 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,252 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:17,260 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:17,268 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/d47d762e-65b6-4b97-8cf4-0fdbcce7505d
2025-10-20 03:07:17,280 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5430186190404929728', 'uuid-lslong': '12193382020954690294'}, 'enable': 'true', 'created': '2025-10-20T03:07:16', 'last-modified': '2025-10-20T03:07:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399644'}
2025-10-20 03:07:17,285 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:17,292 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5430186190404929728', 'uuid-lslong': '12193382020954690294'}, 'enable': 'true', 'created': '2025-10-20T03:07:16', 'last-modified': '2025-10-20T03:07:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.391789'}
2025-10-20 03:07:17,297 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:17,302 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5430186190404929728', 'uuid-lslong': '12193382020954690294'}, 'enable': 'true', 'created': '2025-10-20T03:07:16', 'last-modified': '2025-10-20T03:07:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400372'}
2025-10-20 03:07:17,307 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:17,307 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-27928732 passed
2025-10-20 03:07:17,308 - DEBUG - ====Verifying policy data for ctest-left_vn-27928732 in API_Server ======
2025-10-20 03:07:17,308 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:17,314 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:17,328 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:17,429 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4b5be591-df51-48c0-a937-985841a392f6
2025-10-20 03:07:17,440 - DEBUG - =>VN ctest-left_vn-27928732 has no policy to be verified
2025-10-20 03:07:17,440 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:17,440 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:17,440 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:17,452 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732?flat'}
2025-10-20 03:07:17,452 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:17,453 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:17,470 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732?flat'}
2025-10-20 03:07:17,470 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:17,470 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:17,483 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732?flat'}
2025-10-20 03:07:17,483 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55161050:ctest-left_vn-27928732 is found in opserver
2025-10-20 03:07:17,502 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:17,511 - DEBUG - VRF ids for VN ctest-left_vn-27928732: {}
2025-10-20 03:07:17,785 - DEBUG - Response for create_network : {'network': {'id': '32dccbc2-885c-4c44-b354-4b7f31de2cc5', 'name': 'ctest-right_vn-11205209', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'project_id': '73927ea28f96455682aab551e37d47fa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55161050', 'ctest-right_vn-11205209'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:17,983 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f64ac7d4-8aea-41a9-bdb4-df2c28721747', 'name': '', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'network_id': '32dccbc2-885c-4c44-b354-4b7f31de2cc5', 'ip_version': 4, 'cidr': '41.71.234.128/26', 'allocation_pools': [{'start': '41.71.234.130', 'end': '41.71.234.190'}], 'gateway_ip': '41.71.234.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '41.71.234.130', 'tags': [], 'project_id': '73927ea28f96455682aab551e37d47fa'}}
2025-10-20 03:07:18,004 - INFO - Created VN ctest-right_vn-11205209
2025-10-20 03:07:18,015 - DEBUG - VN ctest-right_vn-11205209 UUID is 32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,100 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:18,108 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:18,119 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:18,213 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,225 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,234 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:18,243 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:18,250 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c36d6619-6cc0-41ee-8b82-c9e86eb86b51
2025-10-20 03:07:18,256 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:18,256 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,265 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:18,283 - INFO - Verified VN network id 13 for VN 32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,283 - INFO - Verifications in API Server for VN ctest-right_vn-11205209 passed
2025-10-20 03:07:18,283 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,293 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:18,300 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:18,308 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c36d6619-6cc0-41ee-8b82-c9e86eb86b51
2025-10-20 03:07:18,320 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3665028233144585284', 'uuid-lslong': '12922036240502369477'}, 'enable': 'true', 'created': '2025-10-20T03:07:17', 'last-modified': '2025-10-20T03:07:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392351'}
2025-10-20 03:07:18,327 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:18,332 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3665028233144585284', 'uuid-lslong': '12922036240502369477'}, 'enable': 'true', 'created': '2025-10-20T03:07:17', 'last-modified': '2025-10-20T03:07:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403097'}
2025-10-20 03:07:18,338 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:18,344 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3665028233144585284', 'uuid-lslong': '12922036240502369477'}, 'enable': 'true', 'created': '2025-10-20T03:07:17', 'last-modified': '2025-10-20T03:07:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416272'}
2025-10-20 03:07:18,350 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:18,350 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-11205209 passed
2025-10-20 03:07:18,351 - DEBUG - ====Verifying policy data for ctest-right_vn-11205209 in API_Server ======
2025-10-20 03:07:18,351 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:18,358 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:18,370 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:18,462 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/32dccbc2-885c-4c44-b354-4b7f31de2cc5
2025-10-20 03:07:18,478 - DEBUG - =>VN ctest-right_vn-11205209 has no policy to be verified
2025-10-20 03:07:18,478 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:18,479 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:18,479 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:18,489 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209?flat'}
2025-10-20 03:07:18,489 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:18,489 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:18,503 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209?flat'}
2025-10-20 03:07:18,503 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:18,504 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:18,515 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209?flat'}
2025-10-20 03:07:18,515 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55161050:ctest-right_vn-11205209 is found in opserver
2025-10-20 03:07:18,535 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:18,546 - DEBUG - VRF ids for VN ctest-right_vn-11205209: {}
2025-10-20 03:07:18,840 - DEBUG - Response for create_network : {'network': {'id': 'cf82c5ca-a1ce-483a-b225-ee1ff3b689b5', 'name': 'ctest-trans_left_vn-97019403', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'project_id': '73927ea28f96455682aab551e37d47fa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55161050', 'ctest-trans_left_vn-97019403'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:19,031 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a1b0d5db-0839-4bac-bd75-eb6c884b808a', 'name': '', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'network_id': 'cf82c5ca-a1ce-483a-b225-ee1ff3b689b5', 'ip_version': 4, 'cidr': '175.109.27.64/26', 'allocation_pools': [{'start': '175.109.27.66', 'end': '175.109.27.126'}], 'gateway_ip': '175.109.27.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '175.109.27.66', 'tags': [], 'project_id': '73927ea28f96455682aab551e37d47fa'}}
2025-10-20 03:07:19,054 - INFO - Created VN ctest-trans_left_vn-97019403
2025-10-20 03:07:19,068 - DEBUG - VN ctest-trans_left_vn-97019403 UUID is cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,144 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:19,150 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:19,162 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:19,250 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,265 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,273 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:19,280 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:19,287 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/0a684f90-3c71-423a-8265-0d191fb00738
2025-10-20 03:07:19,294 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:07:19,294 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,303 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:19,318 - INFO - Verified VN network id 14 for VN cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,318 - INFO - Verifications in API Server for VN ctest-trans_left_vn-97019403 passed
2025-10-20 03:07:19,318 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,328 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:19,335 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:19,342 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/0a684f90-3c71-423a-8265-0d191fb00738
2025-10-20 03:07:19,354 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14952731186912184378', 'uuid-lslong': '12836928133889690037'}, 'enable': 'true', 'created': '2025-10-20T03:07:18', 'last-modified': '2025-10-20T03:07:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.373538'}
2025-10-20 03:07:19,359 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:07:19,365 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14952731186912184378', 'uuid-lslong': '12836928133889690037'}, 'enable': 'true', 'created': '2025-10-20T03:07:18', 'last-modified': '2025-10-20T03:07:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.376670'}
2025-10-20 03:07:19,370 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:07:19,377 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14952731186912184378', 'uuid-lslong': '12836928133889690037'}, 'enable': 'true', 'created': '2025-10-20T03:07:18', 'last-modified': '2025-10-20T03:07:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389646'}
2025-10-20 03:07:19,383 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-20 03:07:19,383 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-97019403 passed
2025-10-20 03:07:19,383 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-97019403 in API_Server ======
2025-10-20 03:07:19,383 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:19,389 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:19,400 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:19,498 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/cf82c5ca-a1ce-483a-b225-ee1ff3b689b5
2025-10-20 03:07:19,521 - DEBUG - =>VN ctest-trans_left_vn-97019403 has no policy to be verified
2025-10-20 03:07:19,521 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:19,521 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:19,521 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:19,535 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403?flat'}
2025-10-20 03:07:19,535 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:19,535 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:19,550 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403?flat'}
2025-10-20 03:07:19,550 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:19,550 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:19,565 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403?flat'}
2025-10-20 03:07:19,565 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55161050:ctest-trans_left_vn-97019403 is found in opserver
2025-10-20 03:07:19,584 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:19,593 - DEBUG - VRF ids for VN ctest-trans_left_vn-97019403: {}
2025-10-20 03:07:19,879 - DEBUG - Response for create_network : {'network': {'id': '67e572aa-5f7d-4228-b0b6-ed90412b063a', 'name': 'ctest-trans_right_vn-40999082', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'project_id': '73927ea28f96455682aab551e37d47fa', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-55161050', 'ctest-trans_right_vn-40999082'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:20,155 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c300c554-9e7e-4409-a97a-12de7e45660a', 'name': '', 'tenant_id': '73927ea28f96455682aab551e37d47fa', 'network_id': '67e572aa-5f7d-4228-b0b6-ed90412b063a', 'ip_version': 4, 'cidr': '191.198.30.128/26', 'allocation_pools': [{'start': '191.198.30.130', 'end': '191.198.30.190'}], 'gateway_ip': '191.198.30.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '191.198.30.130', 'tags': [], 'project_id': '73927ea28f96455682aab551e37d47fa'}}
2025-10-20 03:07:20,184 - INFO - Created VN ctest-trans_right_vn-40999082
2025-10-20 03:07:20,199 - DEBUG - VN ctest-trans_right_vn-40999082 UUID is 67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,276 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:20,284 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:20,295 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:20,387 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,399 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,409 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:20,416 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:20,422 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/e5ca5107-1d45-4585-9119-6d12f2b3a9a3
2025-10-20 03:07:20,428 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:07:20,428 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,437 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:20,452 - INFO - Verified VN network id 15 for VN 67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,452 - INFO - Verifications in API Server for VN ctest-trans_right_vn-40999082 passed
2025-10-20 03:07:20,452 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,463 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:20,470 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:20,476 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/e5ca5107-1d45-4585-9119-6d12f2b3a9a3
2025-10-20 03:07:20,487 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7486516031645368872', 'uuid-lslong': '12733626200261068346'}, 'enable': 'true', 'created': '2025-10-20T03:07:19', 'last-modified': '2025-10-20T03:07:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392893'}
2025-10-20 03:07:20,492 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:07:20,498 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7486516031645368872', 'uuid-lslong': '12733626200261068346'}, 'enable': 'true', 'created': '2025-10-20T03:07:19', 'last-modified': '2025-10-20T03:07:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397618'}
2025-10-20 03:07:20,504 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:07:20,510 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-55161050', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7486516031645368872', 'uuid-lslong': '12733626200261068346'}, 'enable': 'true', 'created': '2025-10-20T03:07:19', 'last-modified': '2025-10-20T03:07:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411685'}
2025-10-20 03:07:20,517 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-20 03:07:20,517 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-40999082 passed
2025-10-20 03:07:20,517 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-40999082 in API_Server ======
2025-10-20 03:07:20,517 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:20,524 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:20,534 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:20,641 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/67e572aa-5f7d-4228-b0b6-ed90412b063a
2025-10-20 03:07:20,650 - DEBUG - =>VN ctest-trans_right_vn-40999082 has no policy to be verified
2025-10-20 03:07:20,650 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:20,650 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:20,650 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:20,662 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082?flat'}
2025-10-20 03:07:20,662 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:20,662 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:20,676 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082?flat'}
2025-10-20 03:07:20,676 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:20,676 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:20,692 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082?flat'}
2025-10-20 03:07:20,692 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-55161050:ctest-trans_right_vn-40999082 is found in opserver
2025-10-20 03:07:20,717 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:20,728 - DEBUG - VRF ids for VN ctest-trans_right_vn-40999082: {}
2025-10-20 03:07:21,774 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:07:23,104 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1)
2025-10-20 03:07:23,105 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-52929936']
2025-10-20 03:07:23,411 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-52929936'] created succesfully
2025-10-20 03:07:23,717 - DEBUG - Image by name tiny_trans_fw not found
2025-10-20 03:07:23,717 - DEBUG - Installing image tiny_trans_fw
2025-10-20 03:07:23,717 - 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-10-20 03:07:23,718 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:07:23,718 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:07:23,751 - 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-10-20 03:07:23,782 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:07:23,782 - DEBUG - Unzip image
2025-10-20 03:07:23,782 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-20 03:07:23,800 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-20 03:07:23,801 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-52929936']
2025-10-20 03:07:23,913 - INFO - Service template: ['default-domain', 'ctest-service_template_1-52929936'] deleted successfully.
2025-10-20 03:07:23,913 - INFO - Skip interface_detach for VM ctest-right_vm-14649946 in state BUILD
2025-10-20 03:07:23,913 - INFO - Deleting VM ctest-right_vm-14649946
2025-10-20 03:07:23,913 - ERROR - VM ctest-right_vm-14649946 has failed to come up
2025-10-20 03:07:23,913 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:23,913 - ERROR - VM ctest-right_vm-14649946 has failed to come up
2025-10-20 03:07:23,913 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:24,006 - INFO - Skip interface_detach for VM ctest-left_vm-19109829 in state BUILD
2025-10-20 03:07:24,006 - INFO - Deleting VM ctest-left_vm-19109829
2025-10-20 03:07:24,006 - ERROR - VM ctest-left_vm-19109829 has failed to come up
2025-10-20 03:07:24,006 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:24,006 - ERROR - VM ctest-left_vm-19109829 has failed to come up
2025-10-20 03:07:24,007 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:24,095 - INFO - Deleting VN ctest-trans_right_vn-40999082
2025-10-20 03:07:24,326 - DEBUG - Response for deleting network ()
2025-10-20 03:07:24,326 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e8441dc2-a66c-4c5a-9949-a8f91674c81a
2025-10-20 03:07:24,332 - DEBUG - Response Code: 404
2025-10-20 03:07:24,332 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:24,338 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:24,361 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:24,463 - INFO - Validated that VN ctest-trans_right_vn-40999082 is not found in API Server
2025-10-20 03:07:24,482 - DEBUG - VN ctest-trans_right_vn-40999082 is not present in Agent 10.0.0.131
2025-10-20 03:07:24,498 - DEBUG - VN ctest-trans_right_vn-40999082 is not present in Agent 10.0.0.19
2025-10-20 03:07:24,498 - INFO - Validated that VN ctest-trans_right_vn-40999082 is not in any agent
2025-10-20 03:07:24,560 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-40999082 info
2025-10-20 03:07:24,560 - INFO - Deleting VN ctest-trans_left_vn-97019403
2025-10-20 03:07:24,741 - DEBUG - Response for deleting network ()
2025-10-20 03:07:24,741 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/b90824fd-8b0f-4b66-990c-7643154fd98f
2025-10-20 03:07:24,746 - DEBUG - Response Code: 404
2025-10-20 03:07:24,746 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:24,764 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:24,783 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:24,896 - INFO - Validated that VN ctest-trans_left_vn-97019403 is not found in API Server
2025-10-20 03:07:24,916 - DEBUG - VN ctest-trans_left_vn-97019403 is not present in Agent 10.0.0.131
2025-10-20 03:07:24,930 - DEBUG - VN ctest-trans_left_vn-97019403 is not present in Agent 10.0.0.19
2025-10-20 03:07:24,931 - INFO - Validated that VN ctest-trans_left_vn-97019403 is not in any agent
2025-10-20 03:07:24,975 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-97019403 info
2025-10-20 03:07:24,975 - INFO - Deleting VN ctest-right_vn-11205209
2025-10-20 03:07:25,012 - DEBUG - VN 32dccbc2-885c-4c44-b354-4b7f31de2cc5 still in use: Unable to complete operation on network 32dccbc2-885c-4c44-b354-4b7f31de2cc5. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a15c3069-f591-4a48-8925-5384d50ef14d']
2025-10-20 03:07:25,012 - WARNING - Deleting VN ctest-right_vn-11205209 failed..Will retry
2025-10-20 03:07:27,185 - DEBUG - Response for deleting network ()
2025-10-20 03:07:27,185 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/aadbb5f3-8f80-43f2-96d3-2a2d55176a8e
2025-10-20 03:07:27,190 - DEBUG - Response Code: 404
2025-10-20 03:07:27,190 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:27,195 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:27,213 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:27,295 - INFO - Validated that VN ctest-right_vn-11205209 is not found in API Server
2025-10-20 03:07:27,308 - DEBUG - VN ctest-right_vn-11205209 is not present in Agent 10.0.0.131
2025-10-20 03:07:27,318 - DEBUG - VN ctest-right_vn-11205209 is not present in Agent 10.0.0.19
2025-10-20 03:07:27,318 - INFO - Validated that VN ctest-right_vn-11205209 is not in any agent
2025-10-20 03:07:27,345 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-11205209 info
2025-10-20 03:07:27,346 - INFO - Deleting VN ctest-left_vn-27928732
2025-10-20 03:07:27,503 - DEBUG - Response for deleting network ()
2025-10-20 03:07:27,503 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e23460c1-1d9a-4930-bfa9-b1c5fef9b505
2025-10-20 03:07:27,508 - DEBUG - Response Code: 404
2025-10-20 03:07:27,508 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:27,513 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:27,566 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:27,668 - INFO - Validated that VN ctest-left_vn-27928732 is not found in API Server
2025-10-20 03:07:27,678 - DEBUG - VN ctest-left_vn-27928732 is not present in Agent 10.0.0.131
2025-10-20 03:07:27,686 - DEBUG - VN ctest-left_vn-27928732 is not present in Agent 10.0.0.19
2025-10-20 03:07:27,686 - INFO - Validated that VN ctest-left_vn-27928732 is not in any agent
2025-10-20 03:07:27,716 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-27928732 info
2025-10-20 03:07:27,716 - INFO - Deleting VN ctest-mgmt_vn-71919888
2025-10-20 03:07:27,862 - DEBUG - Response for deleting network ()
2025-10-20 03:07:27,862 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/7a19604f-cdbe-425b-bb47-d979cc238ea6
2025-10-20 03:07:27,876 - DEBUG - Response Code: 404
2025-10-20 03:07:27,876 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:27,888 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:27,900 - DEBUG - Requesting: http://10.0.0.22:8082/project/73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:28,002 - INFO - Validated that VN ctest-mgmt_vn-71919888 is not found in API Server
2025-10-20 03:07:28,013 - DEBUG - VN ctest-mgmt_vn-71919888 is not present in Agent 10.0.0.131
2025-10-20 03:07:28,033 - DEBUG - VN ctest-mgmt_vn-71919888 is not present in Agent 10.0.0.19
2025-10-20 03:07:28,033 - INFO - Validated that VN ctest-mgmt_vn-71919888 is not in any agent
2025-10-20 03:07:28,066 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-71919888 info
2025-10-20 03:07:29,329 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:07:29,329 - INFO -
2025-10-20 03:07:29,329 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-10-20 03:07:29,329 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:07:30,021 - INFO - Deleted project: ctest-TestSvcRegr-55161050, ID : 73927ea2-8f96-4556-82aa-b551e37d47fa
2025-10-20 03:07:30,189 - INFO - Domain Default found not creating
2025-10-20 03:07:30,336 - INFO - Project ctest-TestSvcRegr-30489230 not found, creating it
2025-10-20 03:07:30,868 - INFO - Created Project:ctest-TestSvcRegr-30489230, ID : a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:32,269 - INFO - ================================================================================
2025-10-20 03:07:32,269 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-20 03:07:33,532 - DEBUG - Nothing to compare xmpp stats {'10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.19': {'10.20.0.193': '0', '10.20.0.14': '0'}} with
2025-10-20 03:07:33,533 - INFO - Initial checks done. Running the testcase now
2025-10-20 03:07:33,533 - INFO -
2025-10-20 03:07:34,213 - DEBUG - Response for create_network : {'network': {'id': 'c681d3f3-beb4-4ac4-9605-86ff228ddde0', 'name': 'ctest-mgmt_vn-64292136', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'ctest-mgmt_vn-64292136'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:34,447 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c552f3a7-d5fe-4eed-aaeb-3a065b3438af', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': 'c681d3f3-beb4-4ac4-9605-86ff228ddde0', 'ip_version': 4, 'cidr': '71.254.246.64/26', 'allocation_pools': [{'start': '71.254.246.66', 'end': '71.254.246.126'}], 'gateway_ip': '71.254.246.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.254.246.66', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:34,469 - INFO - Created VN ctest-mgmt_vn-64292136
2025-10-20 03:07:34,480 - DEBUG - VN ctest-mgmt_vn-64292136 UUID is c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:34,553 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:34,847 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:34,873 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:34,887 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:34,977 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:34,988 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:34,998 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:35,005 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:35,011 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c01a2c83-ae18-4f78-95d5-8700be32d34d
2025-10-20 03:07:35,022 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:35,022 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:35,030 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:35,045 - INFO - Verified VN network id 11 for VN c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:35,045 - INFO - Verifications in API Server for VN ctest-mgmt_vn-64292136 passed
2025-10-20 03:07:35,045 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:35,054 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:35,061 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:35,067 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c01a2c83-ae18-4f78-95d5-8700be32d34d
2025-10-20 03:07:35,080 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303946935335406276', 'uuid-lslong': '10810194910927248864'}, 'enable': 'true', 'created': '2025-10-20T03:07:34', 'last-modified': '2025-10-20T03:07:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.682287'}
2025-10-20 03:07:35,085 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:35,091 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303946935335406276', 'uuid-lslong': '10810194910927248864'}, 'enable': 'true', 'created': '2025-10-20T03:07:34', 'last-modified': '2025-10-20T03:07:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.691035'}
2025-10-20 03:07:35,098 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:35,103 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14303946935335406276', 'uuid-lslong': '10810194910927248864'}, 'enable': 'true', 'created': '2025-10-20T03:07:34', 'last-modified': '2025-10-20T03:07:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.712837'}
2025-10-20 03:07:35,110 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:35,110 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-64292136 passed
2025-10-20 03:07:35,111 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-64292136 in API_Server ======
2025-10-20 03:07:35,111 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:35,116 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:35,131 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:35,232 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c681d3f3-beb4-4ac4-9605-86ff228ddde0
2025-10-20 03:07:35,243 - DEBUG - =>VN ctest-mgmt_vn-64292136 has no policy to be verified
2025-10-20 03:07:35,243 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:35,243 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:35,243 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:35,541 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:35,597 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136?flat'}
2025-10-20 03:07:35,597 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:35,597 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:35,893 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:35,948 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136?flat'}
2025-10-20 03:07:35,948 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:35,948 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:36,238 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:36,293 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136?flat'}
2025-10-20 03:07:36,293 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-64292136 is found in opserver
2025-10-20 03:07:36,315 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:36,326 - DEBUG - VRF ids for VN ctest-mgmt_vn-64292136: {}
2025-10-20 03:07:36,596 - DEBUG - Response for create_network : {'network': {'id': '7d25ca21-3558-4e4a-9e6f-f39865f62325', 'name': 'service-left-vn-53205504', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'service-left-vn-53205504'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:36,864 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd57e1ae4-74d2-4077-a9c8-25fd082cfc81', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': '7d25ca21-3558-4e4a-9e6f-f39865f62325', 'ip_version': 4, 'cidr': '182.200.208.192/26', 'allocation_pools': [{'start': '182.200.208.194', 'end': '182.200.208.254'}], 'gateway_ip': '182.200.208.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.200.208.194', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:36,890 - INFO - Created VN service-left-vn-53205504
2025-10-20 03:07:36,904 - DEBUG - VN service-left-vn-53205504 UUID is 7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:36,974 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:36,979 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:36,990 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:37,084 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,099 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,109 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:37,115 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:37,129 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/25f273bc-489e-46eb-a7fe-7450c7318b9f
2025-10-20 03:07:37,139 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:37,139 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,148 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:37,162 - INFO - Verified VN network id 12 for VN 7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,162 - INFO - Verifications in API Server for VN service-left-vn-53205504 passed
2025-10-20 03:07:37,162 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,173 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:37,182 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:37,190 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/25f273bc-489e-46eb-a7fe-7450c7318b9f
2025-10-20 03:07:37,202 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9017836072856997450', 'uuid-lslong': '11416611416278704933'}, 'enable': 'true', 'created': '2025-10-20T03:07:36', 'last-modified': '2025-10-20T03:07:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.398795'}
2025-10-20 03:07:37,208 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:37,213 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9017836072856997450', 'uuid-lslong': '11416611416278704933'}, 'enable': 'true', 'created': '2025-10-20T03:07:36', 'last-modified': '2025-10-20T03:07:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414855'}
2025-10-20 03:07:37,220 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:37,226 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9017836072856997450', 'uuid-lslong': '11416611416278704933'}, 'enable': 'true', 'created': '2025-10-20T03:07:36', 'last-modified': '2025-10-20T03:07:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426693'}
2025-10-20 03:07:37,233 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:37,233 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-53205504 passed
2025-10-20 03:07:37,233 - DEBUG - ====Verifying policy data for service-left-vn-53205504 in API_Server ======
2025-10-20 03:07:37,233 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:37,239 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:37,249 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:37,342 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/7d25ca21-3558-4e4a-9e6f-f39865f62325
2025-10-20 03:07:37,351 - DEBUG - =>VN service-left-vn-53205504 has no policy to be verified
2025-10-20 03:07:37,351 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:37,351 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:37,351 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:37,407 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504?flat'}
2025-10-20 03:07:37,407 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:37,407 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:37,459 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504?flat'}
2025-10-20 03:07:37,459 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:37,459 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:37,515 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504?flat'}
2025-10-20 03:07:37,515 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:service-left-vn-53205504 is found in opserver
2025-10-20 03:07:37,534 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:37,544 - DEBUG - VRF ids for VN service-left-vn-53205504: {}
2025-10-20 03:07:37,802 - DEBUG - Response for create_network : {'network': {'id': 'd44cf1bc-53c3-4a69-8341-81e40d4c5f1f', 'name': 'service-right-vn-23244984', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'service-right-vn-23244984'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:38,075 - DEBUG - Response for create_subnet : {'subnet': {'id': 'abf2d20b-bf3a-4187-86f5-4d9fb26a021a', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': 'd44cf1bc-53c3-4a69-8341-81e40d4c5f1f', 'ip_version': 4, 'cidr': '101.192.33.128/26', 'allocation_pools': [{'start': '101.192.33.130', 'end': '101.192.33.190'}], 'gateway_ip': '101.192.33.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '101.192.33.130', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:38,099 - INFO - Created VN service-right-vn-23244984
2025-10-20 03:07:38,108 - DEBUG - VN service-right-vn-23244984 UUID is d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,176 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:38,182 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:38,191 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:38,277 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,287 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,295 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:38,303 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:38,309 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/31ba56c7-e0d0-4c5e-8f3e-73eb84f2bb4c
2025-10-20 03:07:38,315 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:38,315 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,322 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:38,336 - INFO - Verified VN network id 13 for VN d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,337 - INFO - Verifications in API Server for VN service-right-vn-23244984 passed
2025-10-20 03:07:38,337 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,346 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:38,353 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:38,359 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/31ba56c7-e0d0-4c5e-8f3e-73eb84f2bb4c
2025-10-20 03:07:38,369 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15297867825432185449', 'uuid-lslong': '9457983508930387743'}, 'enable': 'true', 'created': '2025-10-20T03:07:37', 'last-modified': '2025-10-20T03:07:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.341302'}
2025-10-20 03:07:38,373 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:38,379 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15297867825432185449', 'uuid-lslong': '9457983508930387743'}, 'enable': 'true', 'created': '2025-10-20T03:07:37', 'last-modified': '2025-10-20T03:07:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.353660'}
2025-10-20 03:07:38,385 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:38,390 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15297867825432185449', 'uuid-lslong': '9457983508930387743'}, 'enable': 'true', 'created': '2025-10-20T03:07:37', 'last-modified': '2025-10-20T03:07:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364943'}
2025-10-20 03:07:38,396 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:38,396 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-23244984 passed
2025-10-20 03:07:38,396 - DEBUG - ====Verifying policy data for service-right-vn-23244984 in API_Server ======
2025-10-20 03:07:38,396 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:38,402 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:38,412 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:38,503 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/d44cf1bc-53c3-4a69-8341-81e40d4c5f1f
2025-10-20 03:07:38,521 - DEBUG - =>VN service-right-vn-23244984 has no policy to be verified
2025-10-20 03:07:38,521 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:38,521 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:38,521 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:38,572 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984?flat'}
2025-10-20 03:07:38,572 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:38,572 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:38,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984?flat'}
2025-10-20 03:07:38,625 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:38,625 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:38,676 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984?flat'}
2025-10-20 03:07:38,676 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:service-right-vn-23244984 is found in opserver
2025-10-20 03:07:38,695 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:38,703 - DEBUG - VRF ids for VN service-right-vn-23244984: {}
2025-10-20 03:07:38,761 - DEBUG - Services list from nova: [, , , ]
2025-10-20 03:07:39,750 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:07:41,056 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1)
2025-10-20 03:07:41,056 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-06328668']
2025-10-20 03:07:41,376 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-06328668'] created succesfully
2025-10-20 03:07:41,774 - DEBUG - Image by name tiny_nat_fw not found
2025-10-20 03:07:41,774 - DEBUG - Installing image tiny_nat_fw
2025-10-20 03:07:41,774 - 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-10-20 03:07:41,774 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:41,775 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:41,775 - DEBUG - Unzip image
2025-10-20 03:07:41,775 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:41,794 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-20 03:07:41,794 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-06328668']
2025-10-20 03:07:41,899 - INFO - Service template: ['default-domain', 'ctest-service_template_1-06328668'] deleted successfully.
2025-10-20 03:07:41,899 - INFO - Skip interface_detach for VM ctest-right_vm-58257889 in state BUILD
2025-10-20 03:07:41,899 - INFO - Deleting VM ctest-right_vm-58257889
2025-10-20 03:07:41,935 - ERROR - VM ctest-right_vm-58257889 has failed to come up
2025-10-20 03:07:41,935 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:41,935 - ERROR - VM ctest-right_vm-58257889 has failed to come up
2025-10-20 03:07:41,935 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:42,009 - INFO - Skip interface_detach for VM ctest-left_vm-40378884 in state BUILD
2025-10-20 03:07:42,009 - INFO - Deleting VM ctest-left_vm-40378884
2025-10-20 03:07:42,009 - ERROR - VM ctest-left_vm-40378884 has failed to come up
2025-10-20 03:07:42,009 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:42,009 - ERROR - VM ctest-left_vm-40378884 has failed to come up
2025-10-20 03:07:42,010 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:42,091 - INFO - Deleting VN service-right-vn-23244984
2025-10-20 03:07:42,141 - DEBUG - VN d44cf1bc-53c3-4a69-8341-81e40d4c5f1f still in use: Unable to complete operation on network d44cf1bc-53c3-4a69-8341-81e40d4c5f1f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3fa93207-fc2e-4e53-b024-c33de4a78445']
2025-10-20 03:07:42,141 - WARNING - Deleting VN service-right-vn-23244984 failed..Will retry
2025-10-20 03:07:44,300 - DEBUG - Response for deleting network ()
2025-10-20 03:07:44,300 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/a44b9848-8cd7-47f3-a148-16f9b6d3f210
2025-10-20 03:07:44,315 - DEBUG - Response Code: 404
2025-10-20 03:07:44,315 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:44,327 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:44,384 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:44,478 - INFO - Validated that VN service-right-vn-23244984 is not found in API Server
2025-10-20 03:07:44,488 - DEBUG - VN service-right-vn-23244984 is not present in Agent 10.0.0.131
2025-10-20 03:07:44,497 - DEBUG - VN service-right-vn-23244984 is not present in Agent 10.0.0.19
2025-10-20 03:07:44,497 - INFO - Validated that VN service-right-vn-23244984 is not in any agent
2025-10-20 03:07:44,524 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-23244984 info
2025-10-20 03:07:44,524 - INFO - Deleting VN service-left-vn-53205504
2025-10-20 03:07:44,678 - DEBUG - Response for deleting network ()
2025-10-20 03:07:44,678 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/51c44ab1-d3a2-4263-aede-2f16b7e6abf7
2025-10-20 03:07:44,683 - DEBUG - Response Code: 404
2025-10-20 03:07:44,683 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:44,698 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:44,708 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:44,792 - INFO - Validated that VN service-left-vn-53205504 is not found in API Server
2025-10-20 03:07:44,803 - DEBUG - VN service-left-vn-53205504 is not present in Agent 10.0.0.131
2025-10-20 03:07:44,811 - DEBUG - VN service-left-vn-53205504 is not present in Agent 10.0.0.19
2025-10-20 03:07:44,811 - INFO - Validated that VN service-left-vn-53205504 is not in any agent
2025-10-20 03:07:44,838 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-53205504 info
2025-10-20 03:07:44,838 - INFO - Deleting VN ctest-mgmt_vn-64292136
2025-10-20 03:07:44,983 - DEBUG - Response for deleting network ()
2025-10-20 03:07:44,983 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/21390114-21ef-47b8-8e6f-0e792ddf73e6
2025-10-20 03:07:44,988 - DEBUG - Response Code: 404
2025-10-20 03:07:44,988 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:45,011 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:45,026 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:45,119 - INFO - Validated that VN ctest-mgmt_vn-64292136 is not found in API Server
2025-10-20 03:07:45,130 - DEBUG - VN ctest-mgmt_vn-64292136 is not present in Agent 10.0.0.131
2025-10-20 03:07:45,139 - DEBUG - VN ctest-mgmt_vn-64292136 is not present in Agent 10.0.0.19
2025-10-20 03:07:45,139 - INFO - Validated that VN ctest-mgmt_vn-64292136 is not in any agent
2025-10-20 03:07:45,176 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-64292136 info
2025-10-20 03:07:46,436 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:07:46,436 - INFO -
2025-10-20 03:07:46,436 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-10-20 03:07:46,436 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:07:46,439 - INFO - ================================================================================
2025-10-20 03:07:46,439 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-20 03:07:47,722 - DEBUG - Nothing to compare xmpp stats {'10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.19': {'10.20.0.193': '0', '10.20.0.14': '0'}} with
2025-10-20 03:07:47,723 - INFO - Initial checks done. Running the testcase now
2025-10-20 03:07:47,723 - INFO -
2025-10-20 03:07:47,960 - DEBUG - Response for create_network : {'network': {'id': 'dd6915cc-d966-468f-9cd6-f9d9937be3e7', 'name': 'ctest-mgmt_vn-89599357', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'ctest-mgmt_vn-89599357'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:48,224 - DEBUG - Response for create_subnet : {'subnet': {'id': '89d5b230-8319-44fc-8f08-edcb3e2a5d21', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': 'dd6915cc-d966-468f-9cd6-f9d9937be3e7', 'ip_version': 4, 'cidr': '107.153.108.0/26', 'allocation_pools': [{'start': '107.153.108.2', 'end': '107.153.108.62'}], 'gateway_ip': '107.153.108.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '107.153.108.2', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:48,246 - INFO - Created VN ctest-mgmt_vn-89599357
2025-10-20 03:07:48,256 - DEBUG - VN ctest-mgmt_vn-89599357 UUID is dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,328 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:48,335 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:48,355 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:48,447 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,459 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,468 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:48,475 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:48,482 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/985930ca-32df-4cdc-ac7e-9c81f2519007
2025-10-20 03:07:48,489 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:48,489 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,500 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:48,517 - INFO - Verified VN network id 11 for VN dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,517 - INFO - Verifications in API Server for VN ctest-mgmt_vn-89599357 passed
2025-10-20 03:07:48,517 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,527 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:48,536 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:48,546 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/985930ca-32df-4cdc-ac7e-9c81f2519007
2025-10-20 03:07:48,560 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15954307124501563023', 'uuid-lslong': '11301495027810427879'}, 'enable': 'true', 'created': '2025-10-20T03:07:47', 'last-modified': '2025-10-20T03:07:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387566'}
2025-10-20 03:07:48,567 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:48,573 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15954307124501563023', 'uuid-lslong': '11301495027810427879'}, 'enable': 'true', 'created': '2025-10-20T03:07:47', 'last-modified': '2025-10-20T03:07:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397590'}
2025-10-20 03:07:48,579 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:48,587 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15954307124501563023', 'uuid-lslong': '11301495027810427879'}, 'enable': 'true', 'created': '2025-10-20T03:07:47', 'last-modified': '2025-10-20T03:07:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411666'}
2025-10-20 03:07:48,593 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-20 03:07:48,593 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-89599357 passed
2025-10-20 03:07:48,594 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-89599357 in API_Server ======
2025-10-20 03:07:48,594 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:48,600 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:48,610 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:48,697 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/dd6915cc-d966-468f-9cd6-f9d9937be3e7
2025-10-20 03:07:48,708 - DEBUG - =>VN ctest-mgmt_vn-89599357 has no policy to be verified
2025-10-20 03:07:48,708 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:48,708 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:48,708 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:48,721 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357?flat'}
2025-10-20 03:07:48,721 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:48,721 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:48,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357?flat'}
2025-10-20 03:07:48,735 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:48,735 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:48,747 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357?flat'}
2025-10-20 03:07:48,747 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:ctest-mgmt_vn-89599357 is found in opserver
2025-10-20 03:07:48,766 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:48,775 - DEBUG - VRF ids for VN ctest-mgmt_vn-89599357: {}
2025-10-20 03:07:49,079 - DEBUG - Response for create_network : {'network': {'id': 'b65dd2bb-1447-40ae-9e6b-c4c469063fa7', 'name': 'ctest-left_vn-02880681', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'ctest-left_vn-02880681'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:49,320 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f785aa06-0929-4be8-b2ec-6e2000ab9283', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': 'b65dd2bb-1447-40ae-9e6b-c4c469063fa7', 'ip_version': 4, 'cidr': '191.127.71.0/26', 'allocation_pools': [{'start': '191.127.71.2', 'end': '191.127.71.62'}], 'gateway_ip': '191.127.71.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '191.127.71.2', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:49,346 - INFO - Created VN ctest-left_vn-02880681
2025-10-20 03:07:49,357 - DEBUG - VN ctest-left_vn-02880681 UUID is b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,435 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:49,440 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:49,451 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:49,544 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,555 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,564 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:49,574 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:49,581 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fa40d9f5-bc0f-41f9-bf94-5fe18c24e639
2025-10-20 03:07:49,587 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:49,587 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,596 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:49,612 - INFO - Verified VN network id 12 for VN b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,613 - INFO - Verifications in API Server for VN ctest-left_vn-02880681 passed
2025-10-20 03:07:49,613 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,624 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:49,631 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:49,640 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fa40d9f5-bc0f-41f9-bf94-5fe18c24e639
2025-10-20 03:07:49,650 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13140890988677906606', 'uuid-lslong': '11415434028355305383'}, 'enable': 'true', 'created': '2025-10-20T03:07:49', 'last-modified': '2025-10-20T03:07:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387865'}
2025-10-20 03:07:49,656 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:49,663 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13140890988677906606', 'uuid-lslong': '11415434028355305383'}, 'enable': 'true', 'created': '2025-10-20T03:07:49', 'last-modified': '2025-10-20T03:07:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396610'}
2025-10-20 03:07:49,678 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:49,691 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13140890988677906606', 'uuid-lslong': '11415434028355305383'}, 'enable': 'true', 'created': '2025-10-20T03:07:49', 'last-modified': '2025-10-20T03:07:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425126'}
2025-10-20 03:07:49,699 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-20 03:07:49,699 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-02880681 passed
2025-10-20 03:07:49,699 - DEBUG - ====Verifying policy data for ctest-left_vn-02880681 in API_Server ======
2025-10-20 03:07:49,699 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:49,706 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:49,729 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:49,849 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/b65dd2bb-1447-40ae-9e6b-c4c469063fa7
2025-10-20 03:07:49,881 - DEBUG - =>VN ctest-left_vn-02880681 has no policy to be verified
2025-10-20 03:07:49,881 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:49,881 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:49,881 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:49,900 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681?flat'}
2025-10-20 03:07:49,900 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:49,900 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:49,925 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681?flat'}
2025-10-20 03:07:49,925 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:49,925 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:49,945 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681?flat'}
2025-10-20 03:07:49,945 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:ctest-left_vn-02880681 is found in opserver
2025-10-20 03:07:49,966 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:49,980 - DEBUG - VRF ids for VN ctest-left_vn-02880681: {}
2025-10-20 03:07:50,303 - DEBUG - Response for create_network : {'network': {'id': '5dee3853-49b1-458b-8a28-e9c194018ebb', 'name': 'ctest-right_vn-25434717', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'project_id': 'a9d89ae343e4411a9a2016846805066b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30489230', 'ctest-right_vn-25434717'], 'port_security_enabled': True, 'description': ''}}
2025-10-20 03:07:50,506 - DEBUG - Response for create_subnet : {'subnet': {'id': '4a42d12c-41fa-4aaf-bea7-9a9eab9df245', 'name': '', 'tenant_id': 'a9d89ae343e4411a9a2016846805066b', 'network_id': '5dee3853-49b1-458b-8a28-e9c194018ebb', 'ip_version': 4, 'cidr': '94.245.126.128/26', 'allocation_pools': [{'start': '94.245.126.130', 'end': '94.245.126.190'}], 'gateway_ip': '94.245.126.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '94.245.126.130', 'tags': [], 'project_id': 'a9d89ae343e4411a9a2016846805066b'}}
2025-10-20 03:07:50,529 - INFO - Created VN ctest-right_vn-25434717
2025-10-20 03:07:50,541 - DEBUG - VN ctest-right_vn-25434717 UUID is 5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,608 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:50,613 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:50,625 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:50,720 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,733 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,741 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:50,748 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:50,753 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/44dc523c-5bb0-494e-845b-d12385967d02
2025-10-20 03:07:50,760 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:50,760 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,768 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:50,782 - INFO - Verified VN network id 13 for VN 5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,782 - INFO - Verifications in API Server for VN ctest-right_vn-25434717 passed
2025-10-20 03:07:50,783 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,792 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:50,799 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:50,806 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/44dc523c-5bb0-494e-845b-d12385967d02
2025-10-20 03:07:50,817 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6768409220354229643', 'uuid-lslong': '9955463993923571387'}, 'enable': 'true', 'created': '2025-10-20T03:07:50', 'last-modified': '2025-10-20T03:07:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364274'}
2025-10-20 03:07:50,822 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:50,828 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6768409220354229643', 'uuid-lslong': '9955463993923571387'}, 'enable': 'true', 'created': '2025-10-20T03:07:50', 'last-modified': '2025-10-20T03:07:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377484'}
2025-10-20 03:07:50,835 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:50,841 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30489230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6768409220354229643', 'uuid-lslong': '9955463993923571387'}, 'enable': 'true', 'created': '2025-10-20T03:07:50', 'last-modified': '2025-10-20T03:07:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392055'}
2025-10-20 03:07:50,847 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-20 03:07:50,847 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-25434717 passed
2025-10-20 03:07:50,847 - DEBUG - ====Verifying policy data for ctest-right_vn-25434717 in API_Server ======
2025-10-20 03:07:50,847 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:50,854 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:50,867 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:50,959 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5dee3853-49b1-458b-8a28-e9c194018ebb
2025-10-20 03:07:50,971 - DEBUG - =>VN ctest-right_vn-25434717 has no policy to be verified
2025-10-20 03:07:50,971 - DEBUG - Verifying the vn in opserver
2025-10-20 03:07:50,971 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717 virtual network link through opserver 10.0.0.22
2025-10-20 03:07:50,971 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-10-20 03:07:50,982 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717?flat'}
2025-10-20 03:07:50,982 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717 virtual network link through opserver 10.0.0.254
2025-10-20 03:07:50,982 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-20 03:07:50,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717?flat'}
2025-10-20 03:07:50,995 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717 virtual network link through opserver 10.0.0.27
2025-10-20 03:07:50,995 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-10-20 03:07:51,007 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717?flat'}
2025-10-20 03:07:51,007 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30489230:ctest-right_vn-25434717 is found in opserver
2025-10-20 03:07:51,028 - DEBUG - Do not have enough data to verify VN in agent
2025-10-20 03:07:51,037 - DEBUG - VRF ids for VN ctest-right_vn-25434717: {}
2025-10-20 03:07:52,035 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-1)
2025-10-20 03:07:53,418 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4370-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4370-2)
2025-10-20 03:07:53,418 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-43629969']
2025-10-20 03:07:53,708 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-43629969'] created succesfully
2025-10-20 03:07:54,062 - DEBUG - Image by name tiny_nat_fw not found
2025-10-20 03:07:54,062 - DEBUG - Installing image tiny_nat_fw
2025-10-20 03:07:54,062 - 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-10-20 03:07:54,062 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:54,062 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:54,062 - DEBUG - Unzip image
2025-10-20 03:07:54,062 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-20 03:07:54,081 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-20 03:07:54,085 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-43629969']
2025-10-20 03:07:54,185 - INFO - Service template: ['default-domain', 'ctest-service_template_1-43629969'] deleted successfully.
2025-10-20 03:07:54,185 - INFO - Skip interface_detach for VM ctest-right_vm-33330257 in state BUILD
2025-10-20 03:07:54,185 - INFO - Deleting VM ctest-right_vm-33330257
2025-10-20 03:07:54,185 - ERROR - VM ctest-right_vm-33330257 has failed to come up
2025-10-20 03:07:54,185 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:54,185 - ERROR - VM ctest-right_vm-33330257 has failed to come up
2025-10-20 03:07:54,185 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:54,287 - INFO - Skip interface_detach for VM ctest-left_vm-55741480 in state BUILD
2025-10-20 03:07:54,288 - INFO - Deleting VM ctest-left_vm-55741480
2025-10-20 03:07:54,288 - ERROR - VM ctest-left_vm-55741480 has failed to come up
2025-10-20 03:07:54,288 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:54,288 - ERROR - VM ctest-left_vm-55741480 has failed to come up
2025-10-20 03:07:54,288 - ERROR - Nova failed to get host of the VM
2025-10-20 03:07:54,376 - INFO - Deleting VN ctest-right_vn-25434717
2025-10-20 03:07:54,430 - DEBUG - VN 5dee3853-49b1-458b-8a28-e9c194018ebb still in use: Unable to complete operation on network 5dee3853-49b1-458b-8a28-e9c194018ebb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0d47da58-a7af-43c2-a118-4a554f447e8b']
2025-10-20 03:07:54,430 - WARNING - Deleting VN ctest-right_vn-25434717 failed..Will retry
2025-10-20 03:07:56,571 - DEBUG - Response for deleting network ()
2025-10-20 03:07:56,571 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/2bc70dc6-f4f2-465f-90f6-0b5e713d27bb
2025-10-20 03:07:56,580 - DEBUG - Response Code: 404
2025-10-20 03:07:56,581 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:56,599 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:56,616 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:56,713 - INFO - Validated that VN ctest-right_vn-25434717 is not found in API Server
2025-10-20 03:07:56,724 - DEBUG - VN ctest-right_vn-25434717 is not present in Agent 10.0.0.131
2025-10-20 03:07:56,732 - DEBUG - VN ctest-right_vn-25434717 is not present in Agent 10.0.0.19
2025-10-20 03:07:56,732 - INFO - Validated that VN ctest-right_vn-25434717 is not in any agent
2025-10-20 03:07:56,759 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-25434717 info
2025-10-20 03:07:56,759 - INFO - Deleting VN ctest-left_vn-02880681
2025-10-20 03:07:56,914 - DEBUG - Response for deleting network ()
2025-10-20 03:07:56,914 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/4bf841bf-e064-4ac6-8243-9e6554aff458
2025-10-20 03:07:56,922 - DEBUG - Response Code: 404
2025-10-20 03:07:56,922 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:56,928 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:56,951 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:57,045 - INFO - Validated that VN ctest-left_vn-02880681 is not found in API Server
2025-10-20 03:07:57,058 - DEBUG - VN ctest-left_vn-02880681 is not present in Agent 10.0.0.131
2025-10-20 03:07:57,066 - DEBUG - VN ctest-left_vn-02880681 is not present in Agent 10.0.0.19
2025-10-20 03:07:57,066 - INFO - Validated that VN ctest-left_vn-02880681 is not in any agent
2025-10-20 03:07:57,092 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-02880681 info
2025-10-20 03:07:57,092 - INFO - Deleting VN ctest-mgmt_vn-89599357
2025-10-20 03:07:57,238 - DEBUG - Response for deleting network ()
2025-10-20 03:07:57,238 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/70cf9f1c-abd2-4f0a-bd56-e6d36bfab905
2025-10-20 03:07:57,244 - DEBUG - Response Code: 404
2025-10-20 03:07:57,244 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2025-10-20 03:07:57,253 - DEBUG - Requesting: http://10.0.0.22:8082/domain/9567af48-d216-4b74-bb40-c5fce52cf633
2025-10-20 03:07:57,307 - DEBUG - Requesting: http://10.0.0.22:8082/project/a9d89ae3-43e4-411a-9a20-16846805066b
2025-10-20 03:07:57,401 - INFO - Validated that VN ctest-mgmt_vn-89599357 is not found in API Server
2025-10-20 03:07:57,413 - DEBUG - VN ctest-mgmt_vn-89599357 is not present in Agent 10.0.0.131
2025-10-20 03:07:57,423 - DEBUG - VN ctest-mgmt_vn-89599357 is not present in Agent 10.0.0.19
2025-10-20 03:07:57,423 - INFO - Validated that VN ctest-mgmt_vn-89599357 is not in any agent
2025-10-20 03:07:57,450 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-89599357 info
2025-10-20 03:07:58,735 - DEBUG - No XMPP flaps were noticed during the test
2025-10-20 03:07:58,736 - INFO -
2025-10-20 03:07:58,736 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-20 03:07:58,736 - INFO - --------------------------------------------------------------------------------
2025-10-20 03:07:59,443 - INFO - Deleted project: ctest-TestSvcRegr-30489230, ID : a9d89ae3-43e4-411a-9a20-16846805066b