]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-2)
2026-04-20 17:45:12,610 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-79384448']
2026-04-20 17:45:12,951 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-79384448'] created succesfully
2026-04-20 17:45:13,446 - DEBUG - Image by name tiny_nat_fw not found
2026-04-20 17:45:13,446 - DEBUG - Installing image tiny_nat_fw
2026-04-20 17:45:13,446 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-04-20 17:45:13,446 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:13,447 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:13,481 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:13,660 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:13,660 - DEBUG - Unzip image
2026-04-20 17:45:13,660 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:13,694 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-04-20 17:45:13,694 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-79384448']
2026-04-20 17:45:13,794 - INFO - Service template: ['default-domain', 'ctest-service_template_1-79384448'] deleted successfully.
2026-04-20 17:45:13,795 - INFO - Skip interface_detach for VM ctest-right_vm-64944044 in state BUILD
2026-04-20 17:45:13,795 - INFO - Deleting VM ctest-right_vm-64944044
2026-04-20 17:45:13,841 - ERROR - VM ctest-right_vm-64944044 has failed to come up
2026-04-20 17:45:13,841 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:13,841 - ERROR - VM ctest-right_vm-64944044 has failed to come up
2026-04-20 17:45:13,841 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:13,995 - INFO - Skip interface_detach for VM ctest-left_vm-44502819 in state BUILD
2026-04-20 17:45:13,995 - INFO - Deleting VM ctest-left_vm-44502819
2026-04-20 17:45:13,995 - ERROR - VM ctest-left_vm-44502819 has failed to come up
2026-04-20 17:45:13,995 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:13,995 - ERROR - VM ctest-left_vm-44502819 has failed to come up
2026-04-20 17:45:13,995 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:14,148 - INFO - Deleting VN ctest-right_vn-68450096
2026-04-20 17:45:14,229 - DEBUG - VN e0dcf10d-14c8-4c4e-87c6-d6240538b1ec still in use: Unable to complete operation on network e0dcf10d-14c8-4c4e-87c6-d6240538b1ec. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c84610b7-ec34-479b-b933-9f630e5887a2']
2026-04-20 17:45:14,229 - WARNING - Deleting VN ctest-right_vn-68450096 failed..Will retry
2026-04-20 17:45:16,498 - DEBUG - Response for deleting network ()
2026-04-20 17:45:16,498 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/61274adb-c201-4569-91ba-c36dddfecd57
2026-04-20 17:45:16,512 - DEBUG - Response Code: 404
2026-04-20 17:45:16,512 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:16,522 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:16,549 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:16,672 - INFO - Validated that VN ctest-right_vn-68450096 is not found in API Server
2026-04-20 17:45:16,687 - DEBUG - VN ctest-right_vn-68450096 is not present in Agent 10.0.0.50
2026-04-20 17:45:16,701 - DEBUG - VN ctest-right_vn-68450096 is not present in Agent 10.0.0.130
2026-04-20 17:45:16,701 - INFO - Validated that VN ctest-right_vn-68450096 is not in any agent
2026-04-20 17:45:16,740 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-68450096 info
2026-04-20 17:45:16,740 - INFO - Deleting VN ctest-left_vn-24070890
2026-04-20 17:45:16,951 - DEBUG - Response for deleting network ()
2026-04-20 17:45:16,951 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/df76e779-0efd-4783-ba3f-fb8bf0015437
2026-04-20 17:45:16,969 - DEBUG - Response Code: 404
2026-04-20 17:45:16,969 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:16,981 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:16,999 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:17,112 - INFO - Validated that VN ctest-left_vn-24070890 is not found in API Server
2026-04-20 17:45:17,127 - DEBUG - VN ctest-left_vn-24070890 is not present in Agent 10.0.0.50
2026-04-20 17:45:17,147 - DEBUG - VN ctest-left_vn-24070890 is not present in Agent 10.0.0.130
2026-04-20 17:45:17,147 - INFO - Validated that VN ctest-left_vn-24070890 is not in any agent
2026-04-20 17:45:17,203 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-24070890 info
2026-04-20 17:45:17,203 - INFO - Deleting VN ctest-mgmt_vn-17070013
2026-04-20 17:45:17,381 - DEBUG - Response for deleting network ()
2026-04-20 17:45:17,381 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/4292ef28-4bb0-4ffa-8ffc-9b7795bcf543
2026-04-20 17:45:17,391 - DEBUG - Response Code: 404
2026-04-20 17:45:17,391 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:17,402 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:17,437 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:17,553 - INFO - Validated that VN ctest-mgmt_vn-17070013 is not found in API Server
2026-04-20 17:45:17,573 - DEBUG - VN ctest-mgmt_vn-17070013 is not present in Agent 10.0.0.50
2026-04-20 17:45:17,587 - DEBUG - VN ctest-mgmt_vn-17070013 is not present in Agent 10.0.0.130
2026-04-20 17:45:17,587 - INFO - Validated that VN ctest-mgmt_vn-17070013 is not in any agent
2026-04-20 17:45:17,632 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-17070013 info
2026-04-20 17:45:17,633 - INFO - Deleting VM ctest-vm-in-third-vn-35517771
2026-04-20 17:45:17,741 - INFO - Deleting VN ctest-third-vn-05439631
2026-04-20 17:45:17,802 - DEBUG - VN 56a436fb-ebf6-4912-92c5-80d188732ac1 still in use: Unable to complete operation on network 56a436fb-ebf6-4912-92c5-80d188732ac1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8ac1221e-0fdb-437c-940a-dd6047926b5a']
2026-04-20 17:45:17,802 - WARNING - Deleting VN ctest-third-vn-05439631 failed..Will retry
2026-04-20 17:45:20,029 - DEBUG - Response for deleting network ()
2026-04-20 17:45:21,331 - DEBUG - No XMPP flaps were noticed during the test
2026-04-20 17:45:21,331 - INFO -
2026-04-20 17:45:21,331 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:30]
2026-04-20 17:45:21,331 - INFO - --------------------------------------------------------------------------------
2026-04-20 17:45:21,335 - INFO - ================================================================================
2026-04-20 17:45:21,335 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-04-20 17:45:22,678 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.25': '0'}} with
2026-04-20 17:45:22,678 - INFO - Initial checks done. Running the testcase now
2026-04-20 17:45:22,678 - INFO -
2026-04-20 17:45:23,127 - DEBUG - Response for create_network : {'network': {'id': '0bbd3aa6-a2cc-4c32-b0a3-217914052697', 'name': 'ctest-mgmt_vn-43401341', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'project_id': 'ef94170b76d340f28c397420b3dab437', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-82534224', 'ctest-mgmt_vn-43401341'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:23,398 - DEBUG - Response for create_subnet : {'subnet': {'id': '56f7a691-85dd-4c4e-b36c-4ff086e93eda', 'name': '', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'network_id': '0bbd3aa6-a2cc-4c32-b0a3-217914052697', 'ip_version': 4, 'cidr': '43.196.7.192/26', 'allocation_pools': [{'start': '43.196.7.194', 'end': '43.196.7.254'}], 'gateway_ip': '43.196.7.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.196.7.194', 'tags': [], 'project_id': 'ef94170b76d340f28c397420b3dab437'}}
2026-04-20 17:45:23,456 - INFO - Created VN ctest-mgmt_vn-43401341
2026-04-20 17:45:23,472 - DEBUG - VN ctest-mgmt_vn-43401341 UUID is 0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,597 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:23,607 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:23,628 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:23,768 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,785 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,803 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:23,818 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:23,828 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/e52306cc-da26-48f0-968f-38ac6321adc2
2026-04-20 17:45:23,840 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:23,840 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,852 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:23,877 - INFO - Verified VN network id 14 for VN 0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,877 - INFO - Verifications in API Server for VN ctest-mgmt_vn-43401341 passed
2026-04-20 17:45:23,877 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:23,890 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:23,902 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:23,913 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/e52306cc-da26-48f0-968f-38ac6321adc2
2026-04-20 17:45:23,932 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845896792385801266', 'uuid-lslong': '12728053775789794967'}, 'enable': 'true', 'created': '2026-04-20T17:45:23', 'last-modified': '2026-04-20T17:45:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.589822'}
2026-04-20 17:45:23,949 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:23,957 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845896792385801266', 'uuid-lslong': '12728053775789794967'}, 'enable': 'true', 'created': '2026-04-20T17:45:23', 'last-modified': '2026-04-20T17:45:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.616254'}
2026-04-20 17:45:23,965 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:23,973 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '845896792385801266', 'uuid-lslong': '12728053775789794967'}, 'enable': 'true', 'created': '2026-04-20T17:45:23', 'last-modified': '2026-04-20T17:45:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.631382'}
2026-04-20 17:45:23,981 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:23,981 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-43401341 passed
2026-04-20 17:45:23,982 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-43401341 in API_Server ======
2026-04-20 17:45:23,982 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:23,992 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:24,014 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:24,161 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0bbd3aa6-a2cc-4c32-b0a3-217914052697
2026-04-20 17:45:24,178 - DEBUG - =>VN ctest-mgmt_vn-43401341 has no policy to be verified
2026-04-20 17:45:24,179 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:24,179 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:24,179 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:24,202 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341?flat'}
2026-04-20 17:45:24,202 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:24,202 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:24,223 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341?flat'}
2026-04-20 17:45:24,223 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:24,223 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:24,242 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341?flat'}
2026-04-20 17:45:24,242 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-82534224:ctest-mgmt_vn-43401341 is found in opserver
2026-04-20 17:45:24,273 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:24,287 - DEBUG - VRF ids for VN ctest-mgmt_vn-43401341: {}
2026-04-20 17:45:24,636 - DEBUG - Response for create_network : {'network': {'id': 'f38d4596-2869-4d75-af11-7bec766eacf1', 'name': 'ctest-left_vn-03811774', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'project_id': 'ef94170b76d340f28c397420b3dab437', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-82534224', 'ctest-left_vn-03811774'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:24,980 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cd6b4708-3451-40e7-b0be-34a61b08c19c', 'name': '', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'network_id': 'f38d4596-2869-4d75-af11-7bec766eacf1', 'ip_version': 4, 'cidr': '138.178.210.192/26', 'allocation_pools': [{'start': '138.178.210.194', 'end': '138.178.210.254'}], 'gateway_ip': '138.178.210.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.178.210.194', 'tags': [], 'project_id': 'ef94170b76d340f28c397420b3dab437'}}
2026-04-20 17:45:25,008 - INFO - Created VN ctest-left_vn-03811774
2026-04-20 17:45:25,026 - DEBUG - VN ctest-left_vn-03811774 UUID is f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,164 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:25,172 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:25,191 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:25,307 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,333 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,347 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:25,359 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:25,372 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0a9e5219-b496-4691-b20a-eac82441a1d4
2026-04-20 17:45:25,386 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:25,386 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,407 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:25,426 - INFO - Verified VN network id 15 for VN f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,427 - INFO - Verifications in API Server for VN ctest-left_vn-03811774 passed
2026-04-20 17:45:25,427 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,440 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:25,450 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:25,462 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/0a9e5219-b496-4691-b20a-eac82441a1d4
2026-04-20 17:45:25,481 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17549759834158091637', 'uuid-lslong': '12615000286770932977'}, 'enable': 'true', 'created': '2026-04-20T17:45:24', 'last-modified': '2026-04-20T17:45:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.605866'}
2026-04-20 17:45:25,490 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:25,498 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17549759834158091637', 'uuid-lslong': '12615000286770932977'}, 'enable': 'true', 'created': '2026-04-20T17:45:24', 'last-modified': '2026-04-20T17:45:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.626272'}
2026-04-20 17:45:25,508 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:25,515 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17549759834158091637', 'uuid-lslong': '12615000286770932977'}, 'enable': 'true', 'created': '2026-04-20T17:45:24', 'last-modified': '2026-04-20T17:45:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.645321'}
2026-04-20 17:45:25,523 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:25,523 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-03811774 passed
2026-04-20 17:45:25,523 - DEBUG - ====Verifying policy data for ctest-left_vn-03811774 in API_Server ======
2026-04-20 17:45:25,523 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:25,532 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:25,550 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:25,661 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f38d4596-2869-4d75-af11-7bec766eacf1
2026-04-20 17:45:25,675 - DEBUG - =>VN ctest-left_vn-03811774 has no policy to be verified
2026-04-20 17:45:25,675 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:25,675 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:25,675 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:25,691 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774?flat'}
2026-04-20 17:45:25,691 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:25,691 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:25,706 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774?flat'}
2026-04-20 17:45:25,706 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:25,706 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:25,722 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774?flat'}
2026-04-20 17:45:25,722 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-82534224:ctest-left_vn-03811774 is found in opserver
2026-04-20 17:45:25,749 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:25,762 - DEBUG - VRF ids for VN ctest-left_vn-03811774: {}
2026-04-20 17:45:26,207 - DEBUG - Response for create_network : {'network': {'id': '9e2fc6c0-6828-4344-8b7e-6f5ee425468d', 'name': 'ctest-right_vn-09198117', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'project_id': 'ef94170b76d340f28c397420b3dab437', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-82534224', 'ctest-right_vn-09198117'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:26,517 - DEBUG - Response for create_subnet : {'subnet': {'id': '32b13d30-9be6-4156-b9a3-6f112f008efd', 'name': '', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'network_id': '9e2fc6c0-6828-4344-8b7e-6f5ee425468d', 'ip_version': 4, 'cidr': '126.141.5.192/26', 'allocation_pools': [{'start': '126.141.5.194', 'end': '126.141.5.254'}], 'gateway_ip': '126.141.5.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.141.5.194', 'tags': [], 'project_id': 'ef94170b76d340f28c397420b3dab437'}}
2026-04-20 17:45:26,553 - INFO - Created VN ctest-right_vn-09198117
2026-04-20 17:45:26,566 - DEBUG - VN ctest-right_vn-09198117 UUID is 9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,671 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:26,679 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:26,698 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:26,819 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,845 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,870 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:26,886 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:26,895 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/d79ce6b3-0bdd-4a17-a117-3ad644409261
2026-04-20 17:45:26,907 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:26,907 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,920 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:26,940 - INFO - Verified VN network id 16 for VN 9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,940 - INFO - Verifications in API Server for VN ctest-right_vn-09198117 passed
2026-04-20 17:45:26,940 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:26,956 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:26,975 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:26,985 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/d79ce6b3-0bdd-4a17-a117-3ad644409261
2026-04-20 17:45:27,007 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11398547711581504324', 'uuid-lslong': '10051593871682782861'}, 'enable': 'true', 'created': '2026-04-20T17:45:26', 'last-modified': '2026-04-20T17:45:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.583081'}
2026-04-20 17:45:27,017 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:27,028 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11398547711581504324', 'uuid-lslong': '10051593871682782861'}, 'enable': 'true', 'created': '2026-04-20T17:45:26', 'last-modified': '2026-04-20T17:45:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.605520'}
2026-04-20 17:45:27,039 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:27,049 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11398547711581504324', 'uuid-lslong': '10051593871682782861'}, 'enable': 'true', 'created': '2026-04-20T17:45:26', 'last-modified': '2026-04-20T17:45:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.623983'}
2026-04-20 17:45:27,057 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:27,058 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-09198117 passed
2026-04-20 17:45:27,058 - DEBUG - ====Verifying policy data for ctest-right_vn-09198117 in API_Server ======
2026-04-20 17:45:27,058 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:27,067 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:27,083 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:27,206 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/9e2fc6c0-6828-4344-8b7e-6f5ee425468d
2026-04-20 17:45:27,228 - DEBUG - =>VN ctest-right_vn-09198117 has no policy to be verified
2026-04-20 17:45:27,228 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:27,228 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:27,228 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:27,251 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117?flat'}
2026-04-20 17:45:27,251 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:27,251 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:27,266 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117?flat'}
2026-04-20 17:45:27,266 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:27,266 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:27,286 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117?flat'}
2026-04-20 17:45:27,287 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-82534224:ctest-right_vn-09198117 is found in opserver
2026-04-20 17:45:27,327 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:27,346 - DEBUG - VRF ids for VN ctest-right_vn-09198117: {}
2026-04-20 17:45:27,846 - DEBUG - Response for create_network : {'network': {'id': '4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5', 'name': 'ctest-trans_left_vn-67221051', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'project_id': 'ef94170b76d340f28c397420b3dab437', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-82534224', 'ctest-trans_left_vn-67221051'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:28,189 - DEBUG - Response for create_subnet : {'subnet': {'id': '5ce16a0c-f306-41b8-85be-88a7ecf376f4', 'name': '', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'network_id': '4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5', 'ip_version': 4, 'cidr': '88.68.225.192/26', 'allocation_pools': [{'start': '88.68.225.194', 'end': '88.68.225.254'}], 'gateway_ip': '88.68.225.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.68.225.194', 'tags': [], 'project_id': 'ef94170b76d340f28c397420b3dab437'}}
2026-04-20 17:45:28,243 - INFO - Created VN ctest-trans_left_vn-67221051
2026-04-20 17:45:28,260 - DEBUG - VN ctest-trans_left_vn-67221051 UUID is 4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,375 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:28,386 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:28,404 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:28,509 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,545 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,557 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:28,570 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:28,590 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/8360b19b-a757-44e3-a2d7-6e7e655b6a6e
2026-04-20 17:45:28,603 - DEBUG - Route Targets: ['target:64512:8000009']
2026-04-20 17:45:28,603 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,618 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:28,639 - INFO - Verified VN network id 17 for VN 4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,639 - INFO - Verifications in API Server for VN ctest-trans_left_vn-67221051 passed
2026-04-20 17:45:28,639 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,652 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:28,665 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:28,677 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/8360b19b-a757-44e3-a2d7-6e7e655b6a6e
2026-04-20 17:45:28,701 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531419792557494114', 'uuid-lslong': '12865275874582451925'}, 'enable': 'true', 'created': '2026-04-20T17:45:27', 'last-modified': '2026-04-20T17:45:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.605919'}
2026-04-20 17:45:28,712 - DEBUG - Route Targets: ['target:64512:8000009']
2026-04-20 17:45:28,723 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531419792557494114', 'uuid-lslong': '12865275874582451925'}, 'enable': 'true', 'created': '2026-04-20T17:45:27', 'last-modified': '2026-04-20T17:45:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.631695'}
2026-04-20 17:45:28,731 - DEBUG - Route Targets: ['target:64512:8000009']
2026-04-20 17:45:28,740 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5531419792557494114', 'uuid-lslong': '12865275874582451925'}, 'enable': 'true', 'created': '2026-04-20T17:45:27', 'last-modified': '2026-04-20T17:45:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.633033'}
2026-04-20 17:45:28,748 - DEBUG - Route Targets: ['target:64512:8000009']
2026-04-20 17:45:28,748 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-67221051 passed
2026-04-20 17:45:28,748 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-67221051 in API_Server ======
2026-04-20 17:45:28,749 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:28,766 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:28,781 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:28,879 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4cc38cfe-9ef4-4f62-b28a-a43ea9e3d6d5
2026-04-20 17:45:28,894 - DEBUG - =>VN ctest-trans_left_vn-67221051 has no policy to be verified
2026-04-20 17:45:28,894 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:28,894 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:28,894 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:28,909 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051?flat'}
2026-04-20 17:45:28,909 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:28,909 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:28,929 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051?flat'}
2026-04-20 17:45:28,929 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:28,929 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:28,946 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051?flat'}
2026-04-20 17:45:28,946 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-82534224:ctest-trans_left_vn-67221051 is found in opserver
2026-04-20 17:45:28,973 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:28,987 - DEBUG - VRF ids for VN ctest-trans_left_vn-67221051: {}
2026-04-20 17:45:29,367 - DEBUG - Response for create_network : {'network': {'id': '6436f3ef-b0e5-4177-b24c-b2d89e25bacd', 'name': 'ctest-trans_right_vn-56033472', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'project_id': 'ef94170b76d340f28c397420b3dab437', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-82534224', 'ctest-trans_right_vn-56033472'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:29,720 - DEBUG - Response for create_subnet : {'subnet': {'id': '050cafed-51e4-4ace-a462-7c75bde4b552', 'name': '', 'tenant_id': 'ef94170b76d340f28c397420b3dab437', 'network_id': '6436f3ef-b0e5-4177-b24c-b2d89e25bacd', 'ip_version': 4, 'cidr': '71.172.237.64/26', 'allocation_pools': [{'start': '71.172.237.66', 'end': '71.172.237.126'}], 'gateway_ip': '71.172.237.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.172.237.66', 'tags': [], 'project_id': 'ef94170b76d340f28c397420b3dab437'}}
2026-04-20 17:45:29,749 - INFO - Created VN ctest-trans_right_vn-56033472
2026-04-20 17:45:29,765 - DEBUG - VN ctest-trans_right_vn-56033472 UUID is 6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:29,885 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:29,893 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:29,916 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:30,032 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,051 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,065 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:30,076 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:30,087 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/88b09c7c-98f1-4ffe-86ca-bf2f9234080a
2026-04-20 17:45:30,096 - DEBUG - Route Targets: ['target:64512:8000010']
2026-04-20 17:45:30,096 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,109 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:30,130 - INFO - Verified VN network id 18 for VN 6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,130 - INFO - Verifications in API Server for VN ctest-trans_right_vn-56033472 passed
2026-04-20 17:45:30,130 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,144 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:30,154 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:30,163 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/88b09c7c-98f1-4ffe-86ca-bf2f9234080a
2026-04-20 17:45:30,180 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7221227263325716855', 'uuid-lslong': '12847840480417135309'}, 'enable': 'true', 'created': '2026-04-20T17:45:29', 'last-modified': '2026-04-20T17:45:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.590503'}
2026-04-20 17:45:30,189 - DEBUG - Route Targets: ['target:64512:8000010']
2026-04-20 17:45:30,196 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7221227263325716855', 'uuid-lslong': '12847840480417135309'}, 'enable': 'true', 'created': '2026-04-20T17:45:29', 'last-modified': '2026-04-20T17:45:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.590744'}
2026-04-20 17:45:30,204 - DEBUG - Route Targets: ['target:64512:8000010']
2026-04-20 17:45:30,211 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-82534224', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7221227263325716855', 'uuid-lslong': '12847840480417135309'}, 'enable': 'true', 'created': '2026-04-20T17:45:29', 'last-modified': '2026-04-20T17:45:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.601946'}
2026-04-20 17:45:30,220 - DEBUG - Route Targets: ['target:64512:8000010']
2026-04-20 17:45:30,220 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-56033472 passed
2026-04-20 17:45:30,221 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-56033472 in API_Server ======
2026-04-20 17:45:30,221 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:30,229 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:30,242 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:30,363 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/6436f3ef-b0e5-4177-b24c-b2d89e25bacd
2026-04-20 17:45:30,382 - DEBUG - =>VN ctest-trans_right_vn-56033472 has no policy to be verified
2026-04-20 17:45:30,382 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:30,382 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:30,382 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:30,398 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472?flat'}
2026-04-20 17:45:30,398 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:30,398 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:30,415 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472?flat'}
2026-04-20 17:45:30,415 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:30,415 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:30,439 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472?flat'}
2026-04-20 17:45:30,439 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-82534224:ctest-trans_right_vn-56033472 is found in opserver
2026-04-20 17:45:30,473 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:30,488 - DEBUG - VRF ids for VN ctest-trans_right_vn-56033472: {}
2026-04-20 17:45:31,941 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-2)
2026-04-20 17:45:33,672 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-1)
2026-04-20 17:45:33,673 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-67037232']
2026-04-20 17:45:34,009 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-67037232'] created succesfully
2026-04-20 17:45:34,614 - DEBUG - Image by name tiny_trans_fw not found
2026-04-20 17:45:34,614 - DEBUG - Installing image tiny_trans_fw
2026-04-20 17:45:34,614 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2026-04-20 17:45:34,614 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-04-20 17:45:34,614 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-04-20 17:45:34,640 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-04-20 17:45:34,705 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-04-20 17:45:34,707 - DEBUG - Unzip image
2026-04-20 17:45:34,707 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-04-20 17:45:34,736 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-04-20 17:45:34,736 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-67037232']
2026-04-20 17:45:34,863 - INFO - Service template: ['default-domain', 'ctest-service_template_1-67037232'] deleted successfully.
2026-04-20 17:45:34,863 - INFO - Skip interface_detach for VM ctest-right_vm-97696694 in state BUILD
2026-04-20 17:45:34,864 - INFO - Deleting VM ctest-right_vm-97696694
2026-04-20 17:45:34,864 - ERROR - VM ctest-right_vm-97696694 has failed to come up
2026-04-20 17:45:34,864 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:34,864 - ERROR - VM ctest-right_vm-97696694 has failed to come up
2026-04-20 17:45:34,864 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:34,998 - INFO - Skip interface_detach for VM ctest-left_vm-01038573 in state BUILD
2026-04-20 17:45:34,999 - INFO - Deleting VM ctest-left_vm-01038573
2026-04-20 17:45:34,999 - ERROR - VM ctest-left_vm-01038573 has failed to come up
2026-04-20 17:45:34,999 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:34,999 - ERROR - VM ctest-left_vm-01038573 has failed to come up
2026-04-20 17:45:34,999 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:35,110 - INFO - Deleting VN ctest-trans_right_vn-56033472
2026-04-20 17:45:35,344 - DEBUG - Response for deleting network ()
2026-04-20 17:45:35,345 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/f78c20db-2cec-4da7-a503-3b1127b8e547
2026-04-20 17:45:35,354 - DEBUG - Response Code: 404
2026-04-20 17:45:35,354 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:35,383 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:35,421 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:35,532 - INFO - Validated that VN ctest-trans_right_vn-56033472 is not found in API Server
2026-04-20 17:45:35,547 - DEBUG - VN ctest-trans_right_vn-56033472 is not present in Agent 10.0.0.50
2026-04-20 17:45:35,566 - DEBUG - VN ctest-trans_right_vn-56033472 is not present in Agent 10.0.0.130
2026-04-20 17:45:35,566 - INFO - Validated that VN ctest-trans_right_vn-56033472 is not in any agent
2026-04-20 17:45:35,615 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-56033472 info
2026-04-20 17:45:35,615 - INFO - Deleting VN ctest-trans_left_vn-67221051
2026-04-20 17:45:35,904 - DEBUG - Response for deleting network ()
2026-04-20 17:45:35,905 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/44a0f112-a620-412e-8670-2d4d050057f6
2026-04-20 17:45:35,924 - DEBUG - Response Code: 404
2026-04-20 17:45:35,924 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:35,935 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:35,956 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:36,069 - INFO - Validated that VN ctest-trans_left_vn-67221051 is not found in API Server
2026-04-20 17:45:36,096 - DEBUG - VN ctest-trans_left_vn-67221051 is not present in Agent 10.0.0.50
2026-04-20 17:45:36,117 - DEBUG - VN ctest-trans_left_vn-67221051 is not present in Agent 10.0.0.130
2026-04-20 17:45:36,118 - INFO - Validated that VN ctest-trans_left_vn-67221051 is not in any agent
2026-04-20 17:45:36,175 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-67221051 info
2026-04-20 17:45:36,176 - INFO - Deleting VN ctest-right_vn-09198117
2026-04-20 17:45:36,238 - DEBUG - VN 9e2fc6c0-6828-4344-8b7e-6f5ee425468d still in use: Unable to complete operation on network 9e2fc6c0-6828-4344-8b7e-6f5ee425468d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-02fe1e29-267d-4494-ad6b-747ccf9b889e']
2026-04-20 17:45:36,238 - WARNING - Deleting VN ctest-right_vn-09198117 failed..Will retry
2026-04-20 17:45:38,435 - DEBUG - Response for deleting network ()
2026-04-20 17:45:38,435 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/e2fefbda-d570-476d-abb4-cdd945b39dcc
2026-04-20 17:45:38,446 - DEBUG - Response Code: 404
2026-04-20 17:45:38,446 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:38,460 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:38,477 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:38,581 - INFO - Validated that VN ctest-right_vn-09198117 is not found in API Server
2026-04-20 17:45:38,596 - DEBUG - VN ctest-right_vn-09198117 is not present in Agent 10.0.0.50
2026-04-20 17:45:38,609 - DEBUG - VN ctest-right_vn-09198117 is not present in Agent 10.0.0.130
2026-04-20 17:45:38,609 - INFO - Validated that VN ctest-right_vn-09198117 is not in any agent
2026-04-20 17:45:38,653 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-09198117 info
2026-04-20 17:45:38,653 - INFO - Deleting VN ctest-left_vn-03811774
2026-04-20 17:45:38,868 - DEBUG - Response for deleting network ()
2026-04-20 17:45:38,868 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/04871c0e-e0e8-41a7-b395-c19630d7dc51
2026-04-20 17:45:38,885 - DEBUG - Response Code: 404
2026-04-20 17:45:38,885 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:38,896 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:38,911 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:39,014 - INFO - Validated that VN ctest-left_vn-03811774 is not found in API Server
2026-04-20 17:45:39,034 - DEBUG - VN ctest-left_vn-03811774 is not present in Agent 10.0.0.50
2026-04-20 17:45:39,047 - DEBUG - VN ctest-left_vn-03811774 is not present in Agent 10.0.0.130
2026-04-20 17:45:39,047 - INFO - Validated that VN ctest-left_vn-03811774 is not in any agent
2026-04-20 17:45:39,087 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-03811774 info
2026-04-20 17:45:39,087 - INFO - Deleting VN ctest-mgmt_vn-43401341
2026-04-20 17:45:39,283 - DEBUG - Response for deleting network ()
2026-04-20 17:45:39,283 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/a50793b1-4557-450b-afb1-153d37b4ddbf
2026-04-20 17:45:39,290 - DEBUG - Response Code: 404
2026-04-20 17:45:39,290 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:39,298 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:39,322 - DEBUG - Requesting: http://10.0.0.23:8082/project/ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:39,434 - INFO - Validated that VN ctest-mgmt_vn-43401341 is not found in API Server
2026-04-20 17:45:39,448 - DEBUG - VN ctest-mgmt_vn-43401341 is not present in Agent 10.0.0.50
2026-04-20 17:45:39,462 - DEBUG - VN ctest-mgmt_vn-43401341 is not present in Agent 10.0.0.130
2026-04-20 17:45:39,462 - INFO - Validated that VN ctest-mgmt_vn-43401341 is not in any agent
2026-04-20 17:45:39,499 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-43401341 info
2026-04-20 17:45:40,814 - DEBUG - No XMPP flaps were noticed during the test
2026-04-20 17:45:40,814 - INFO -
2026-04-20 17:45:40,814 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:19]
2026-04-20 17:45:40,814 - INFO - --------------------------------------------------------------------------------
2026-04-20 17:45:41,691 - INFO - Deleted project: ctest-TestSvcRegr-82534224, ID : ef94170b-76d3-40f2-8c39-7420b3dab437
2026-04-20 17:45:41,966 - INFO - Domain Default found not creating
2026-04-20 17:45:42,206 - INFO - Project ctest-TestSvcRegr-42817154 not found, creating it
2026-04-20 17:45:42,919 - INFO - Created Project:ctest-TestSvcRegr-42817154, ID : b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:44,949 - INFO - ================================================================================
2026-04-20 17:45:44,949 - INFO - STARTING TEST : test_svc_CEM_22032
2026-04-20 17:45:46,228 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.25': '0'}} with
2026-04-20 17:45:46,228 - INFO - Initial checks done. Running the testcase now
2026-04-20 17:45:46,229 - INFO -
2026-04-20 17:45:47,079 - DEBUG - Response for create_network : {'network': {'id': 'b743a5d9-79e8-40e5-a618-556f1a0790e5', 'name': 'ctest-mgmt_vn-37088806', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'ctest-mgmt_vn-37088806'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:47,375 - DEBUG - Response for create_subnet : {'subnet': {'id': '1dd0d9c1-9348-41d0-977a-32b32db272b0', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': 'b743a5d9-79e8-40e5-a618-556f1a0790e5', 'ip_version': 4, 'cidr': '67.225.170.192/26', 'allocation_pools': [{'start': '67.225.170.194', 'end': '67.225.170.254'}], 'gateway_ip': '67.225.170.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '67.225.170.194', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:45:47,410 - INFO - Created VN ctest-mgmt_vn-37088806
2026-04-20 17:45:47,425 - DEBUG - VN ctest-mgmt_vn-37088806 UUID is b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:47,534 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:47,869 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:47,917 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:47,942 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:48,073 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,092 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,106 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:45:48,122 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:45:48,131 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/abe681f5-36a4-46a4-9167-128d593766fc
2026-04-20 17:45:48,142 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:48,142 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,154 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:45:48,173 - INFO - Verified VN network id 14 for VN b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,173 - INFO - Verifications in API Server for VN ctest-mgmt_vn-37088806 passed
2026-04-20 17:45:48,174 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,187 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:45:48,201 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:45:48,210 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/abe681f5-36a4-46a4-9167-128d593766fc
2026-04-20 17:45:48,228 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13205580885852176613', 'uuid-lslong': '11968409945403527397'}, 'enable': 'true', 'created': '2026-04-20T17:45:47', 'last-modified': '2026-04-20T17:45:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.907258'}
2026-04-20 17:45:48,236 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:48,244 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13205580885852176613', 'uuid-lslong': '11968409945403527397'}, 'enable': 'true', 'created': '2026-04-20T17:45:47', 'last-modified': '2026-04-20T17:45:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.930887'}
2026-04-20 17:45:48,253 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:48,262 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13205580885852176613', 'uuid-lslong': '11968409945403527397'}, 'enable': 'true', 'created': '2026-04-20T17:45:47', 'last-modified': '2026-04-20T17:45:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.953209'}
2026-04-20 17:45:48,270 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:45:48,270 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-37088806 passed
2026-04-20 17:45:48,271 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-37088806 in API_Server ======
2026-04-20 17:45:48,271 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:48,280 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:48,298 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:48,429 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b743a5d9-79e8-40e5-a618-556f1a0790e5
2026-04-20 17:45:48,445 - DEBUG - =>VN ctest-mgmt_vn-37088806 has no policy to be verified
2026-04-20 17:45:48,445 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:48,445 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:48,445 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:48,774 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:48,861 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806?flat'}
2026-04-20 17:45:48,861 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:48,861 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:49,187 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:49,286 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806?flat'}
2026-04-20 17:45:49,286 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:49,286 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:49,624 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:49,727 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806?flat'}
2026-04-20 17:45:49,727 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-37088806 is found in opserver
2026-04-20 17:45:49,761 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:49,776 - DEBUG - VRF ids for VN ctest-mgmt_vn-37088806: {}
2026-04-20 17:45:50,117 - DEBUG - Response for create_network : {'network': {'id': '964eec1b-987c-4676-beb1-c4c1b890c7eb', 'name': 'service-left-vn-98730435', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'service-left-vn-98730435'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:50,478 - DEBUG - Response for create_subnet : {'subnet': {'id': '83a712ad-0251-4db3-a5c0-fa4d6e9fe77c', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': '964eec1b-987c-4676-beb1-c4c1b890c7eb', 'ip_version': 4, 'cidr': '79.105.194.0/26', 'allocation_pools': [{'start': '79.105.194.2', 'end': '79.105.194.62'}], 'gateway_ip': '79.105.194.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '79.105.194.2', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:45:50,507 - INFO - Created VN service-left-vn-98730435
2026-04-20 17:45:50,525 - DEBUG - VN service-left-vn-98730435 UUID is 964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,612 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:50,620 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:50,638 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:50,776 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,796 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,808 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:45:50,823 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:45:50,835 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5a0ae1a8-3760-4a69-bea8-2d597e82201e
2026-04-20 17:45:50,850 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:50,850 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,873 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:45:50,902 - INFO - Verified VN network id 15 for VN 964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,902 - INFO - Verifications in API Server for VN service-left-vn-98730435 passed
2026-04-20 17:45:50,902 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:50,917 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:45:50,928 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:45:50,939 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/5a0ae1a8-3760-4a69-bea8-2d597e82201e
2026-04-20 17:45:50,967 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10830853757139175030', 'uuid-lslong': '13740980274388322283'}, 'enable': 'true', 'created': '2026-04-20T17:45:50', 'last-modified': '2026-04-20T17:45:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.554583'}
2026-04-20 17:45:50,978 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:50,986 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10830853757139175030', 'uuid-lslong': '13740980274388322283'}, 'enable': 'true', 'created': '2026-04-20T17:45:50', 'last-modified': '2026-04-20T17:45:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.583549'}
2026-04-20 17:45:50,994 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:51,017 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10830853757139175030', 'uuid-lslong': '13740980274388322283'}, 'enable': 'true', 'created': '2026-04-20T17:45:50', 'last-modified': '2026-04-20T17:45:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.598663'}
2026-04-20 17:45:51,030 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:45:51,030 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-98730435 passed
2026-04-20 17:45:51,031 - DEBUG - ====Verifying policy data for service-left-vn-98730435 in API_Server ======
2026-04-20 17:45:51,031 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:51,041 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:51,061 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:51,183 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/964eec1b-987c-4676-beb1-c4c1b890c7eb
2026-04-20 17:45:51,195 - DEBUG - =>VN service-left-vn-98730435 has no policy to be verified
2026-04-20 17:45:51,195 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:51,195 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:51,195 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:51,303 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435?flat'}
2026-04-20 17:45:51,303 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:51,303 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:51,437 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435?flat'}
2026-04-20 17:45:51,437 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:51,437 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:51,538 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435?flat'}
2026-04-20 17:45:51,538 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:service-left-vn-98730435 is found in opserver
2026-04-20 17:45:51,567 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:51,586 - DEBUG - VRF ids for VN service-left-vn-98730435: {}
2026-04-20 17:45:51,971 - DEBUG - Response for create_network : {'network': {'id': '4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304', 'name': 'service-right-vn-00922485', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'service-right-vn-00922485'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:45:52,261 - DEBUG - Response for create_subnet : {'subnet': {'id': '4b8d259b-9809-4ddd-9a02-ea801fda725d', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': '4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304', 'ip_version': 4, 'cidr': '37.190.226.0/26', 'allocation_pools': [{'start': '37.190.226.2', 'end': '37.190.226.62'}], 'gateway_ip': '37.190.226.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.190.226.2', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:45:52,288 - INFO - Created VN service-right-vn-00922485
2026-04-20 17:45:52,302 - DEBUG - VN service-right-vn-00922485 UUID is 4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,403 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:52,410 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:52,426 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:52,546 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,581 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:45:52,596 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:45:52,610 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/9bcf849b-692c-4da4-9ac5-fd53ee0fab96
2026-04-20 17:45:52,622 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:52,622 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,640 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:45:52,667 - INFO - Verified VN network id 16 for VN 4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,667 - INFO - Verifications in API Server for VN service-right-vn-00922485 passed
2026-04-20 17:45:52,667 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,682 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:45:52,694 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:45:52,706 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/9bcf849b-692c-4da4-9ac5-fd53ee0fab96
2026-04-20 17:45:52,722 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5425510319715600628', 'uuid-lslong': '11972115161305965316'}, 'enable': 'true', 'created': '2026-04-20T17:45:51', 'last-modified': '2026-04-20T17:45:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532091'}
2026-04-20 17:45:52,729 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:52,737 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5425510319715600628', 'uuid-lslong': '11972115161305965316'}, 'enable': 'true', 'created': '2026-04-20T17:45:51', 'last-modified': '2026-04-20T17:45:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.560783'}
2026-04-20 17:45:52,744 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:52,757 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5425510319715600628', 'uuid-lslong': '11972115161305965316'}, 'enable': 'true', 'created': '2026-04-20T17:45:51', 'last-modified': '2026-04-20T17:45:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.562561'}
2026-04-20 17:45:52,764 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:45:52,765 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-00922485 passed
2026-04-20 17:45:52,765 - DEBUG - ====Verifying policy data for service-right-vn-00922485 in API_Server ======
2026-04-20 17:45:52,765 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:45:52,773 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:45:52,794 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:45:52,930 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304
2026-04-20 17:45:52,945 - DEBUG - =>VN service-right-vn-00922485 has no policy to be verified
2026-04-20 17:45:52,946 - DEBUG - Verifying the vn in opserver
2026-04-20 17:45:52,946 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485 virtual network link through opserver 10.0.0.23
2026-04-20 17:45:52,946 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:45:53,046 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485?flat'}
2026-04-20 17:45:53,046 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485 virtual network link through opserver 10.0.0.249
2026-04-20 17:45:53,046 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:45:53,131 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485?flat'}
2026-04-20 17:45:53,131 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485 virtual network link through opserver 10.0.0.65
2026-04-20 17:45:53,131 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:45:53,225 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485?flat'}
2026-04-20 17:45:53,225 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:service-right-vn-00922485 is found in opserver
2026-04-20 17:45:53,252 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:45:53,265 - DEBUG - VRF ids for VN service-right-vn-00922485: {}
2026-04-20 17:45:53,459 - DEBUG - Services list from nova: [, , , ]
2026-04-20 17:45:55,056 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-2)
2026-04-20 17:45:56,817 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-1)
2026-04-20 17:45:56,819 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-90536703']
2026-04-20 17:45:57,169 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-90536703'] created succesfully
2026-04-20 17:45:57,754 - DEBUG - Image by name tiny_nat_fw not found
2026-04-20 17:45:57,754 - DEBUG - Installing image tiny_nat_fw
2026-04-20 17:45:57,754 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-04-20 17:45:57,754 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:57,754 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:57,754 - DEBUG - Unzip image
2026-04-20 17:45:57,754 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:45:57,804 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-04-20 17:45:57,806 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-90536703']
2026-04-20 17:45:57,961 - INFO - Service template: ['default-domain', 'ctest-service_template_1-90536703'] deleted successfully.
2026-04-20 17:45:57,961 - INFO - Skip interface_detach for VM ctest-right_vm-92898709 in state BUILD
2026-04-20 17:45:57,962 - INFO - Deleting VM ctest-right_vm-92898709
2026-04-20 17:45:58,046 - ERROR - VM ctest-right_vm-92898709 has failed to come up
2026-04-20 17:45:58,046 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:58,046 - ERROR - VM ctest-right_vm-92898709 has failed to come up
2026-04-20 17:45:58,046 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:58,201 - INFO - Skip interface_detach for VM ctest-left_vm-33212591 in state BUILD
2026-04-20 17:45:58,201 - INFO - Deleting VM ctest-left_vm-33212591
2026-04-20 17:45:58,201 - ERROR - VM ctest-left_vm-33212591 has failed to come up
2026-04-20 17:45:58,201 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:58,201 - ERROR - VM ctest-left_vm-33212591 has failed to come up
2026-04-20 17:45:58,202 - ERROR - Nova failed to get host of the VM
2026-04-20 17:45:58,393 - INFO - Deleting VN service-right-vn-00922485
2026-04-20 17:45:58,458 - DEBUG - VN 4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304 still in use: Unable to complete operation on network 4b4b48e3-e3dd-48f4-a625-7f4ee7b5e304. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-83f74c77-2b1e-4552-828c-1e83daa0339b']
2026-04-20 17:45:58,458 - WARNING - Deleting VN service-right-vn-00922485 failed..Will retry
2026-04-20 17:46:00,737 - DEBUG - Response for deleting network ()
2026-04-20 17:46:00,737 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/5afd39a0-0c86-4b03-a2eb-de20bf96ac04
2026-04-20 17:46:00,748 - DEBUG - Response Code: 404
2026-04-20 17:46:00,748 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:00,760 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:00,792 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:00,924 - INFO - Validated that VN service-right-vn-00922485 is not found in API Server
2026-04-20 17:46:00,940 - DEBUG - VN service-right-vn-00922485 is not present in Agent 10.0.0.50
2026-04-20 17:46:00,961 - DEBUG - VN service-right-vn-00922485 is not present in Agent 10.0.0.130
2026-04-20 17:46:00,961 - INFO - Validated that VN service-right-vn-00922485 is not in any agent
2026-04-20 17:46:01,017 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-00922485 info
2026-04-20 17:46:01,017 - INFO - Deleting VN service-left-vn-98730435
2026-04-20 17:46:01,279 - DEBUG - Response for deleting network ()
2026-04-20 17:46:01,279 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/aa8ed4aa-d3f2-4efe-871a-c1b547aa1762
2026-04-20 17:46:01,298 - DEBUG - Response Code: 404
2026-04-20 17:46:01,298 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:01,309 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:01,329 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:01,464 - INFO - Validated that VN service-left-vn-98730435 is not found in API Server
2026-04-20 17:46:01,479 - DEBUG - VN service-left-vn-98730435 is not present in Agent 10.0.0.50
2026-04-20 17:46:01,500 - DEBUG - VN service-left-vn-98730435 is not present in Agent 10.0.0.130
2026-04-20 17:46:01,500 - INFO - Validated that VN service-left-vn-98730435 is not in any agent
2026-04-20 17:46:01,552 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-98730435 info
2026-04-20 17:46:01,552 - INFO - Deleting VN ctest-mgmt_vn-37088806
2026-04-20 17:46:01,878 - DEBUG - Response for deleting network ()
2026-04-20 17:46:01,878 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/7a21b5fc-e8af-4279-9025-a6e7f41581dd
2026-04-20 17:46:01,893 - DEBUG - Response Code: 404
2026-04-20 17:46:01,894 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:01,903 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:01,936 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:02,053 - INFO - Validated that VN ctest-mgmt_vn-37088806 is not found in API Server
2026-04-20 17:46:02,072 - DEBUG - VN ctest-mgmt_vn-37088806 is not present in Agent 10.0.0.50
2026-04-20 17:46:02,087 - DEBUG - VN ctest-mgmt_vn-37088806 is not present in Agent 10.0.0.130
2026-04-20 17:46:02,087 - INFO - Validated that VN ctest-mgmt_vn-37088806 is not in any agent
2026-04-20 17:46:02,132 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-37088806 info
2026-04-20 17:46:03,478 - DEBUG - No XMPP flaps were noticed during the test
2026-04-20 17:46:03,479 - INFO -
2026-04-20 17:46:03,479 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:19]
2026-04-20 17:46:03,479 - INFO - --------------------------------------------------------------------------------
2026-04-20 17:46:03,482 - INFO - ================================================================================
2026-04-20 17:46:03,482 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-04-20 17:46:04,803 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.193': '0', '10.20.0.25': '0'}} with
2026-04-20 17:46:04,803 - INFO - Initial checks done. Running the testcase now
2026-04-20 17:46:04,803 - INFO -
2026-04-20 17:46:05,158 - DEBUG - Response for create_network : {'network': {'id': 'ec1f8c32-2d82-4d08-8176-86d25f5a7c40', 'name': 'ctest-mgmt_vn-10700919', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'ctest-mgmt_vn-10700919'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:46:05,452 - DEBUG - Response for create_subnet : {'subnet': {'id': '2516437e-9271-490a-9bcd-6959292f0156', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': 'ec1f8c32-2d82-4d08-8176-86d25f5a7c40', 'ip_version': 4, 'cidr': '128.101.109.64/26', 'allocation_pools': [{'start': '128.101.109.66', 'end': '128.101.109.126'}], 'gateway_ip': '128.101.109.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.101.109.66', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:46:05,487 - INFO - Created VN ctest-mgmt_vn-10700919
2026-04-20 17:46:05,503 - DEBUG - VN ctest-mgmt_vn-10700919 UUID is ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,609 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:05,619 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:05,635 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:05,750 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,766 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,780 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:05,791 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:05,805 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/75f9f55d-12b3-4687-916f-89bed7e4d74b
2026-04-20 17:46:05,815 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:46:05,815 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,827 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:05,849 - INFO - Verified VN network id 14 for VN ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,850 - INFO - Verifications in API Server for VN ctest-mgmt_vn-10700919 passed
2026-04-20 17:46:05,850 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:05,863 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:05,873 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:05,886 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/75f9f55d-12b3-4687-916f-89bed7e4d74b
2026-04-20 17:46:05,907 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17014472064368790792', 'uuid-lslong': '9328791916245580864'}, 'enable': 'true', 'created': '2026-04-20T17:46:05', 'last-modified': '2026-04-20T17:46:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.538124'}
2026-04-20 17:46:05,914 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:46:05,923 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17014472064368790792', 'uuid-lslong': '9328791916245580864'}, 'enable': 'true', 'created': '2026-04-20T17:46:05', 'last-modified': '2026-04-20T17:46:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549004'}
2026-04-20 17:46:05,933 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:46:05,940 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17014472064368790792', 'uuid-lslong': '9328791916245580864'}, 'enable': 'true', 'created': '2026-04-20T17:46:05', 'last-modified': '2026-04-20T17:46:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.568520'}
2026-04-20 17:46:05,948 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-20 17:46:05,948 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-10700919 passed
2026-04-20 17:46:05,949 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-10700919 in API_Server ======
2026-04-20 17:46:05,949 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:05,957 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:05,973 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:06,090 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/ec1f8c32-2d82-4d08-8176-86d25f5a7c40
2026-04-20 17:46:06,103 - DEBUG - =>VN ctest-mgmt_vn-10700919 has no policy to be verified
2026-04-20 17:46:06,103 - DEBUG - Verifying the vn in opserver
2026-04-20 17:46:06,103 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919 virtual network link through opserver 10.0.0.23
2026-04-20 17:46:06,103 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:46:06,119 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919?flat'}
2026-04-20 17:46:06,120 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919 virtual network link through opserver 10.0.0.249
2026-04-20 17:46:06,120 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:46:06,139 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919?flat'}
2026-04-20 17:46:06,139 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919 virtual network link through opserver 10.0.0.65
2026-04-20 17:46:06,139 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:46:06,156 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919?flat'}
2026-04-20 17:46:06,156 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:ctest-mgmt_vn-10700919 is found in opserver
2026-04-20 17:46:06,185 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:46:06,200 - DEBUG - VRF ids for VN ctest-mgmt_vn-10700919: {}
2026-04-20 17:46:06,567 - DEBUG - Response for create_network : {'network': {'id': '0072bb34-b4eb-446c-9735-45014f18e85a', 'name': 'ctest-left_vn-91563544', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'ctest-left_vn-91563544'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:46:06,852 - DEBUG - Response for create_subnet : {'subnet': {'id': '149de7a3-9243-4e9b-8f0c-ffa944025e30', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': '0072bb34-b4eb-446c-9735-45014f18e85a', 'ip_version': 4, 'cidr': '194.190.208.0/26', 'allocation_pools': [{'start': '194.190.208.2', 'end': '194.190.208.62'}], 'gateway_ip': '194.190.208.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '194.190.208.2', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:46:06,887 - INFO - Created VN ctest-left_vn-91563544
2026-04-20 17:46:06,905 - DEBUG - VN ctest-left_vn-91563544 UUID is 0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:06,993 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:07,003 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:07,019 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:07,141 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,162 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,174 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:07,185 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:07,195 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6a445cca-73a6-4bf3-ad16-601a56ca0c96
2026-04-20 17:46:07,204 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:46:07,204 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,218 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:07,240 - INFO - Verified VN network id 15 for VN 0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,241 - INFO - Verifications in API Server for VN ctest-left_vn-91563544 passed
2026-04-20 17:46:07,241 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,255 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:07,266 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:07,276 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6a445cca-73a6-4bf3-ad16-601a56ca0c96
2026-04-20 17:46:07,293 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32293982393025644', 'uuid-lslong': '10895690745417099354'}, 'enable': 'true', 'created': '2026-04-20T17:46:06', 'last-modified': '2026-04-20T17:46:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.522956'}
2026-04-20 17:46:07,302 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:46:07,309 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32293982393025644', 'uuid-lslong': '10895690745417099354'}, 'enable': 'true', 'created': '2026-04-20T17:46:06', 'last-modified': '2026-04-20T17:46:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.535406'}
2026-04-20 17:46:07,319 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:46:07,327 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '32293982393025644', 'uuid-lslong': '10895690745417099354'}, 'enable': 'true', 'created': '2026-04-20T17:46:06', 'last-modified': '2026-04-20T17:46:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.555509'}
2026-04-20 17:46:07,335 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-20 17:46:07,335 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-91563544 passed
2026-04-20 17:46:07,336 - DEBUG - ====Verifying policy data for ctest-left_vn-91563544 in API_Server ======
2026-04-20 17:46:07,336 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:07,344 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:07,360 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:07,471 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0072bb34-b4eb-446c-9735-45014f18e85a
2026-04-20 17:46:07,483 - DEBUG - =>VN ctest-left_vn-91563544 has no policy to be verified
2026-04-20 17:46:07,483 - DEBUG - Verifying the vn in opserver
2026-04-20 17:46:07,483 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544 virtual network link through opserver 10.0.0.23
2026-04-20 17:46:07,483 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:46:07,500 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544?flat'}
2026-04-20 17:46:07,500 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544 virtual network link through opserver 10.0.0.249
2026-04-20 17:46:07,500 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:46:07,517 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544?flat'}
2026-04-20 17:46:07,517 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544 virtual network link through opserver 10.0.0.65
2026-04-20 17:46:07,517 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:46:07,534 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544?flat'}
2026-04-20 17:46:07,534 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:ctest-left_vn-91563544 is found in opserver
2026-04-20 17:46:07,558 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:46:07,573 - DEBUG - VRF ids for VN ctest-left_vn-91563544: {}
2026-04-20 17:46:07,918 - DEBUG - Response for create_network : {'network': {'id': '0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30', 'name': 'ctest-right_vn-08776777', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-42817154', 'ctest-right_vn-08776777'], 'port_security_enabled': True, 'description': ''}}
2026-04-20 17:46:08,230 - DEBUG - Response for create_subnet : {'subnet': {'id': '13859855-7767-4428-81c0-3ac4248a2f86', 'name': '', 'tenant_id': 'b7cfab3c9b94451d9891528ed503e0e5', 'network_id': '0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30', 'ip_version': 4, 'cidr': '144.137.33.128/26', 'allocation_pools': [{'start': '144.137.33.130', 'end': '144.137.33.190'}], 'gateway_ip': '144.137.33.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '144.137.33.130', 'tags': [], 'project_id': 'b7cfab3c9b94451d9891528ed503e0e5'}}
2026-04-20 17:46:08,260 - INFO - Created VN ctest-right_vn-08776777
2026-04-20 17:46:08,280 - DEBUG - VN ctest-right_vn-08776777 UUID is 0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,382 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:08,389 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:08,403 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:08,511 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,527 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,541 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:08,556 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:08,565 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/3a5be5c4-8885-495a-a442-0069abd9103f
2026-04-20 17:46:08,575 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:46:08,576 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,590 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:08,613 - INFO - Verified VN network id 16 for VN 0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,614 - INFO - Verifications in API Server for VN ctest-right_vn-08776777 passed
2026-04-20 17:46:08,614 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,629 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:08,646 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:08,658 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/3a5be5c4-8885-495a-a442-0069abd9103f
2026-04-20 17:46:08,678 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1132918359000436319', 'uuid-lslong': '13031294949790440752'}, 'enable': 'true', 'created': '2026-04-20T17:46:07', 'last-modified': '2026-04-20T17:46:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.507750'}
2026-04-20 17:46:08,691 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:46:08,699 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1132918359000436319', 'uuid-lslong': '13031294949790440752'}, 'enable': 'true', 'created': '2026-04-20T17:46:07', 'last-modified': '2026-04-20T17:46:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534959'}
2026-04-20 17:46:08,707 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:46:08,716 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-42817154', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1132918359000436319', 'uuid-lslong': '13031294949790440752'}, 'enable': 'true', 'created': '2026-04-20T17:46:07', 'last-modified': '2026-04-20T17:46:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549497'}
2026-04-20 17:46:08,725 - DEBUG - Route Targets: ['target:64512:8000008']
2026-04-20 17:46:08,725 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-08776777 passed
2026-04-20 17:46:08,726 - DEBUG - ====Verifying policy data for ctest-right_vn-08776777 in API_Server ======
2026-04-20 17:46:08,726 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:08,734 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:08,753 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:08,868 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30
2026-04-20 17:46:08,882 - DEBUG - =>VN ctest-right_vn-08776777 has no policy to be verified
2026-04-20 17:46:08,882 - DEBUG - Verifying the vn in opserver
2026-04-20 17:46:08,882 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777 virtual network link through opserver 10.0.0.23
2026-04-20 17:46:08,882 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-20 17:46:08,899 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777?flat'}
2026-04-20 17:46:08,899 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777 virtual network link through opserver 10.0.0.249
2026-04-20 17:46:08,899 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-20 17:46:08,917 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777?flat'}
2026-04-20 17:46:08,917 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777 virtual network link through opserver 10.0.0.65
2026-04-20 17:46:08,917 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2026-04-20 17:46:08,936 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777?flat'}
2026-04-20 17:46:08,936 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-42817154:ctest-right_vn-08776777 is found in opserver
2026-04-20 17:46:08,962 - DEBUG - Do not have enough data to verify VN in agent
2026-04-20 17:46:08,977 - DEBUG - VRF ids for VN ctest-right_vn-08776777: {}
2026-04-20 17:46:10,411 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-1)
2026-04-20 17:46:12,069 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5654-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5654-2)
2026-04-20 17:46:12,069 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-20998287']
2026-04-20 17:46:12,395 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-20998287'] created succesfully
2026-04-20 17:46:12,958 - DEBUG - Image by name tiny_nat_fw not found
2026-04-20 17:46:12,958 - DEBUG - Installing image tiny_nat_fw
2026-04-20 17:46:12,958 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2026-04-20 17:46:12,959 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:46:12,959 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:46:12,959 - DEBUG - Unzip image
2026-04-20 17:46:12,959 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-04-20 17:46:12,986 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-04-20 17:46:12,987 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-20998287']
2026-04-20 17:46:13,117 - INFO - Service template: ['default-domain', 'ctest-service_template_1-20998287'] deleted successfully.
2026-04-20 17:46:13,117 - INFO - Skip interface_detach for VM ctest-right_vm-47711018 in state BUILD
2026-04-20 17:46:13,117 - INFO - Deleting VM ctest-right_vm-47711018
2026-04-20 17:46:13,117 - ERROR - VM ctest-right_vm-47711018 has failed to come up
2026-04-20 17:46:13,117 - ERROR - Nova failed to get host of the VM
2026-04-20 17:46:13,117 - ERROR - VM ctest-right_vm-47711018 has failed to come up
2026-04-20 17:46:13,117 - ERROR - Nova failed to get host of the VM
2026-04-20 17:46:13,305 - INFO - Skip interface_detach for VM ctest-left_vm-17142613 in state BUILD
2026-04-20 17:46:13,305 - INFO - Deleting VM ctest-left_vm-17142613
2026-04-20 17:46:13,305 - ERROR - VM ctest-left_vm-17142613 has failed to come up
2026-04-20 17:46:13,306 - ERROR - Nova failed to get host of the VM
2026-04-20 17:46:13,306 - ERROR - VM ctest-left_vm-17142613 has failed to come up
2026-04-20 17:46:13,306 - ERROR - Nova failed to get host of the VM
2026-04-20 17:46:13,428 - INFO - Deleting VN ctest-right_vn-08776777
2026-04-20 17:46:13,499 - DEBUG - VN 0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30 still in use: Unable to complete operation on network 0fb8ef3e-bbbd-4e5f-b4d8-75b6cd99dd30. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-14cc0e52-08db-4f3f-8e5a-0728a80326e8']
2026-04-20 17:46:13,500 - WARNING - Deleting VN ctest-right_vn-08776777 failed..Will retry
2026-04-20 17:46:15,735 - DEBUG - Response for deleting network ()
2026-04-20 17:46:15,735 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1dfc089f-969c-4da2-ab6b-c7c9ed0c36b1
2026-04-20 17:46:15,744 - DEBUG - Response Code: 404
2026-04-20 17:46:15,744 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:15,762 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:15,789 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:15,903 - INFO - Validated that VN ctest-right_vn-08776777 is not found in API Server
2026-04-20 17:46:15,916 - DEBUG - VN ctest-right_vn-08776777 is not present in Agent 10.0.0.50
2026-04-20 17:46:15,930 - DEBUG - VN ctest-right_vn-08776777 is not present in Agent 10.0.0.130
2026-04-20 17:46:15,930 - INFO - Validated that VN ctest-right_vn-08776777 is not in any agent
2026-04-20 17:46:15,974 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-08776777 info
2026-04-20 17:46:15,974 - INFO - Deleting VN ctest-left_vn-91563544
2026-04-20 17:46:16,220 - DEBUG - Response for deleting network ()
2026-04-20 17:46:16,220 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ca5fd1e4-acee-465a-be8a-fe4960ae7add
2026-04-20 17:46:16,229 - DEBUG - Response Code: 404
2026-04-20 17:46:16,229 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:16,237 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:16,296 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:16,405 - INFO - Validated that VN ctest-left_vn-91563544 is not found in API Server
2026-04-20 17:46:16,417 - DEBUG - VN ctest-left_vn-91563544 is not present in Agent 10.0.0.50
2026-04-20 17:46:16,429 - DEBUG - VN ctest-left_vn-91563544 is not present in Agent 10.0.0.130
2026-04-20 17:46:16,429 - INFO - Validated that VN ctest-left_vn-91563544 is not in any agent
2026-04-20 17:46:16,470 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-91563544 info
2026-04-20 17:46:16,470 - INFO - Deleting VN ctest-mgmt_vn-10700919
2026-04-20 17:46:16,640 - DEBUG - Response for deleting network ()
2026-04-20 17:46:16,640 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/d11bf8e0-8d99-4ef9-ba86-6ec54cafbc2a
2026-04-20 17:46:16,647 - DEBUG - Response Code: 404
2026-04-20 17:46:16,647 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-20 17:46:16,656 - DEBUG - Requesting: http://10.0.0.23:8082/domain/40643203-20f6-446b-8c17-54dd9444dd19
2026-04-20 17:46:16,683 - DEBUG - Requesting: http://10.0.0.23:8082/project/b7cfab3c-9b94-451d-9891-528ed503e0e5
2026-04-20 17:46:16,789 - INFO - Validated that VN ctest-mgmt_vn-10700919 is not found in API Server
2026-04-20 17:46:16,826 - DEBUG - VN ctest-mgmt_vn-10700919 is not present in Agent 10.0.0.50
2026-04-20 17:46:16,839 - DEBUG - VN ctest-mgmt_vn-10700919 is not present in Agent 10.0.0.130
2026-04-20 17:46:16,839 - INFO - Validated that VN ctest-mgmt_vn-10700919 is not in any agent
2026-04-20 17:46:16,879 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-10700919 info
2026-04-20 17:46:18,177 - DEBUG - No XMPP flaps were noticed during the test
2026-04-20 17:46:18,178 - INFO -
2026-04-20 17:46:18,178 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:15]
2026-04-20 17:46:18,178 - INFO - --------------------------------------------------------------------------------
2026-04-20 17:46:19,084 - INFO - Deleted project: ctest-TestSvcRegr-42817154, ID : b7cfab3c-9b94-451d-9891-528ed503e0e5