]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-2)
2025-08-02 23:58:11,811 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-93315144']
2025-08-02 23:58:12,215 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-93315144'] created succesfully
2025-08-02 23:58:12,613 - DEBUG - Image by name tiny_nat_fw not found
2025-08-02 23:58:12,613 - DEBUG - Installing image tiny_nat_fw
2025-08-02 23:58:12,613 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-02 23:58:12,613 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:12,613 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:12,666 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:12,895 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:12,896 - DEBUG - Unzip image
2025-08-02 23:58:12,896 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:12,914 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-02 23:58:12,914 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-93315144']
2025-08-02 23:58:13,045 - INFO - Service template: ['default-domain', 'ctest-service_template_1-93315144'] deleted successfully.
2025-08-02 23:58:13,045 - INFO - Skip interface_detach for VM ctest-right_vm-15066893 in state BUILD
2025-08-02 23:58:13,046 - INFO - Deleting VM ctest-right_vm-15066893
2025-08-02 23:58:13,076 - ERROR - VM ctest-right_vm-15066893 has failed to come up
2025-08-02 23:58:13,076 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:13,077 - ERROR - VM ctest-right_vm-15066893 has failed to come up
2025-08-02 23:58:13,077 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:13,142 - INFO - Skip interface_detach for VM ctest-left_vm-51547902 in state BUILD
2025-08-02 23:58:13,142 - INFO - Deleting VM ctest-left_vm-51547902
2025-08-02 23:58:13,142 - ERROR - VM ctest-left_vm-51547902 has failed to come up
2025-08-02 23:58:13,142 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:13,142 - ERROR - VM ctest-left_vm-51547902 has failed to come up
2025-08-02 23:58:13,142 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:13,208 - INFO - Deleting VN ctest-right_vn-44185656
2025-08-02 23:58:13,284 - DEBUG - VN 31a10e67-f4fe-49b8-8c31-3fd4e6955de1 still in use: Unable to complete operation on network 31a10e67-f4fe-49b8-8c31-3fd4e6955de1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9b2df43a-ef5f-44b6-a6b9-b570e49308bb']
2025-08-02 23:58:13,284 - WARNING - Deleting VN ctest-right_vn-44185656 failed..Will retry
2025-08-02 23:58:15,485 - DEBUG - Response for deleting network ()
2025-08-02 23:58:15,486 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/56b38538-647a-4311-a6a7-617fc062a3bd
2025-08-02 23:58:15,499 - DEBUG - Response Code: 404
2025-08-02 23:58:15,499 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:15,521 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:15,549 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:15,655 - INFO - Validated that VN ctest-right_vn-44185656 is not found in API Server
2025-08-02 23:58:15,669 - DEBUG - VN ctest-right_vn-44185656 is not present in Agent 10.0.0.48
2025-08-02 23:58:15,682 - DEBUG - VN ctest-right_vn-44185656 is not present in Agent 10.0.0.49
2025-08-02 23:58:15,682 - INFO - Validated that VN ctest-right_vn-44185656 is not in any agent
2025-08-02 23:58:15,726 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-44185656 info
2025-08-02 23:58:15,726 - INFO - Deleting VN ctest-left_vn-10248201
2025-08-02 23:58:15,928 - DEBUG - Response for deleting network ()
2025-08-02 23:58:15,928 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/be9e008a-6a34-4492-bc34-60520f353fca
2025-08-02 23:58:15,939 - DEBUG - Response Code: 404
2025-08-02 23:58:15,939 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:15,947 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:15,978 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:16,091 - INFO - Validated that VN ctest-left_vn-10248201 is not found in API Server
2025-08-02 23:58:16,111 - DEBUG - VN ctest-left_vn-10248201 is not present in Agent 10.0.0.48
2025-08-02 23:58:16,135 - DEBUG - VN ctest-left_vn-10248201 is not present in Agent 10.0.0.49
2025-08-02 23:58:16,135 - INFO - Validated that VN ctest-left_vn-10248201 is not in any agent
2025-08-02 23:58:16,199 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-10248201 info
2025-08-02 23:58:16,199 - INFO - Deleting VN ctest-mgmt_vn-35111113
2025-08-02 23:58:16,437 - DEBUG - Response for deleting network ()
2025-08-02 23:58:16,437 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/4f1ea881-3906-41cb-b30a-fb67e187c621
2025-08-02 23:58:16,446 - DEBUG - Response Code: 404
2025-08-02 23:58:16,446 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:16,458 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:16,526 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:16,643 - INFO - Validated that VN ctest-mgmt_vn-35111113 is not found in API Server
2025-08-02 23:58:16,657 - DEBUG - VN ctest-mgmt_vn-35111113 is not present in Agent 10.0.0.48
2025-08-02 23:58:16,673 - DEBUG - VN ctest-mgmt_vn-35111113 is not present in Agent 10.0.0.49
2025-08-02 23:58:16,673 - INFO - Validated that VN ctest-mgmt_vn-35111113 is not in any agent
2025-08-02 23:58:16,726 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-35111113 info
2025-08-02 23:58:16,726 - INFO - Deleting VM ctest-vm-in-third-vn-72845858
2025-08-02 23:58:16,810 - INFO - Deleting VN ctest-third-vn-71995561
2025-08-02 23:58:16,892 - DEBUG - VN dc7edf76-4294-4a31-bd50-b050153d522b still in use: Unable to complete operation on network dc7edf76-4294-4a31-bd50-b050153d522b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e529c9e0-74b8-4cf9-baba-9752448596be']
2025-08-02 23:58:16,892 - WARNING - Deleting VN ctest-third-vn-71995561 failed..Will retry
2025-08-02 23:58:19,165 - DEBUG - Response for deleting network ()
2025-08-02 23:58:20,547 - DEBUG - No XMPP flaps were noticed during the test
2025-08-02 23:58:20,547 - INFO -
2025-08-02 23:58:20,548 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2025-08-02 23:58:20,548 - INFO - --------------------------------------------------------------------------------
2025-08-02 23:58:20,551 - INFO - ================================================================================
2025-08-02 23:58:20,551 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-02 23:58:21,942 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0', '10.20.0.14': '0'}, '10.0.0.49': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-08-02 23:58:21,943 - INFO - Initial checks done. Running the testcase now
2025-08-02 23:58:21,943 - INFO -
2025-08-02 23:58:22,326 - DEBUG - Response for create_network : {'network': {'id': 'b132fb59-9ad1-4291-8846-8f57958673eb', 'name': 'ctest-mgmt_vn-02703751', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'project_id': '543ec5972f7e46dc8804897564058067', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-32165339', 'ctest-mgmt_vn-02703751'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:22,589 - DEBUG - Response for create_subnet : {'subnet': {'id': '2634dbcd-94b6-4ffa-be32-5e141891003f', 'name': '', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'network_id': 'b132fb59-9ad1-4291-8846-8f57958673eb', 'ip_version': 4, 'cidr': '183.166.196.128/26', 'allocation_pools': [{'start': '183.166.196.130', 'end': '183.166.196.190'}], 'gateway_ip': '183.166.196.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '183.166.196.130', 'tags': [], 'project_id': '543ec5972f7e46dc8804897564058067'}}
2025-08-02 23:58:22,618 - INFO - Created VN ctest-mgmt_vn-02703751
2025-08-02 23:58:22,634 - DEBUG - VN ctest-mgmt_vn-02703751 UUID is b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,728 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:22,737 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:22,753 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:22,891 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,904 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,916 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:22,928 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:22,935 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f120dd9f-04c2-466c-bdd1-7d032ed3a594
2025-08-02 23:58:22,943 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:22,943 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,953 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:22,975 - INFO - Verified VN network id 12 for VN b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,976 - INFO - Verifications in API Server for VN ctest-mgmt_vn-02703751 passed
2025-08-02 23:58:22,976 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:22,987 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:22,996 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:23,005 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f120dd9f-04c2-466c-bdd1-7d032ed3a594
2025-08-02 23:58:23,021 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12768544255816843921', 'uuid-lslong': '9819693643861488619'}, 'enable': 'true', 'created': '2025-08-02T23:58:22', 'last-modified': '2025-08-02T23:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487492'}
2025-08-02 23:58:23,029 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:23,042 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12768544255816843921', 'uuid-lslong': '9819693643861488619'}, 'enable': 'true', 'created': '2025-08-02T23:58:22', 'last-modified': '2025-08-02T23:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.510084'}
2025-08-02 23:58:23,059 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:23,073 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12768544255816843921', 'uuid-lslong': '9819693643861488619'}, 'enable': 'true', 'created': '2025-08-02T23:58:22', 'last-modified': '2025-08-02T23:58:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.537160'}
2025-08-02 23:58:23,086 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:23,086 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-02703751 passed
2025-08-02 23:58:23,087 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-02703751 in API_Server ======
2025-08-02 23:58:23,087 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:23,095 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:23,109 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:23,225 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/b132fb59-9ad1-4291-8846-8f57958673eb
2025-08-02 23:58:23,242 - DEBUG - =>VN ctest-mgmt_vn-02703751 has no policy to be verified
2025-08-02 23:58:23,242 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:23,242 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:23,242 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:23,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751?flat'}
2025-08-02 23:58:23,262 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:23,262 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:23,286 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751?flat'}
2025-08-02 23:58:23,286 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:23,286 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:23,305 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751?flat'}
2025-08-02 23:58:23,305 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-32165339:ctest-mgmt_vn-02703751 is found in opserver
2025-08-02 23:58:23,328 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:23,339 - DEBUG - VRF ids for VN ctest-mgmt_vn-02703751: {}
2025-08-02 23:58:23,713 - DEBUG - Response for create_network : {'network': {'id': 'f3810dc1-2e46-496b-9a1c-fe7830cad7aa', 'name': 'ctest-left_vn-11501546', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'project_id': '543ec5972f7e46dc8804897564058067', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-32165339', 'ctest-left_vn-11501546'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:23,940 - DEBUG - Response for create_subnet : {'subnet': {'id': '872c4c43-3276-488d-b572-04ad41a28cdb', 'name': '', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'network_id': 'f3810dc1-2e46-496b-9a1c-fe7830cad7aa', 'ip_version': 4, 'cidr': '13.69.19.128/26', 'allocation_pools': [{'start': '13.69.19.130', 'end': '13.69.19.190'}], 'gateway_ip': '13.69.19.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '13.69.19.130', 'tags': [], 'project_id': '543ec5972f7e46dc8804897564058067'}}
2025-08-02 23:58:23,965 - INFO - Created VN ctest-left_vn-11501546
2025-08-02 23:58:23,985 - DEBUG - VN ctest-left_vn-11501546 UUID is f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,066 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:24,076 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:24,094 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:24,206 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,230 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,244 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:24,256 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:24,268 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/38f86b1b-eecc-4512-8c5d-2711165e78ff
2025-08-02 23:58:24,278 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:24,278 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,290 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:24,321 - INFO - Verified VN network id 13 for VN f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,321 - INFO - Verifications in API Server for VN ctest-left_vn-11501546 passed
2025-08-02 23:58:24,321 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,333 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:24,345 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:24,356 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/38f86b1b-eecc-4512-8c5d-2711165e78ff
2025-08-02 23:58:24,376 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17546320746568370539', 'uuid-lslong': '11105030573356930986'}, 'enable': 'true', 'created': '2025-08-02T23:58:23', 'last-modified': '2025-08-02T23:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497989'}
2025-08-02 23:58:24,384 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:24,398 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17546320746568370539', 'uuid-lslong': '11105030573356930986'}, 'enable': 'true', 'created': '2025-08-02T23:58:23', 'last-modified': '2025-08-02T23:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.527940'}
2025-08-02 23:58:24,414 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:24,428 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17546320746568370539', 'uuid-lslong': '11105030573356930986'}, 'enable': 'true', 'created': '2025-08-02T23:58:23', 'last-modified': '2025-08-02T23:58:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.550600'}
2025-08-02 23:58:24,437 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:24,437 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-11501546 passed
2025-08-02 23:58:24,438 - DEBUG - ====Verifying policy data for ctest-left_vn-11501546 in API_Server ======
2025-08-02 23:58:24,438 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:24,454 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:24,471 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:24,600 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3810dc1-2e46-496b-9a1c-fe7830cad7aa
2025-08-02 23:58:24,614 - DEBUG - =>VN ctest-left_vn-11501546 has no policy to be verified
2025-08-02 23:58:24,614 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:24,614 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:24,614 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:24,637 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546?flat'}
2025-08-02 23:58:24,637 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:24,637 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:24,662 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546?flat'}
2025-08-02 23:58:24,662 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:24,662 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:24,688 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546?flat'}
2025-08-02 23:58:24,689 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-32165339:ctest-left_vn-11501546 is found in opserver
2025-08-02 23:58:24,714 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:24,726 - DEBUG - VRF ids for VN ctest-left_vn-11501546: {}
2025-08-02 23:58:25,175 - DEBUG - Response for create_network : {'network': {'id': '2207f983-427f-43e4-9408-1947306d980d', 'name': 'ctest-right_vn-15654945', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'project_id': '543ec5972f7e46dc8804897564058067', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-32165339', 'ctest-right_vn-15654945'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:25,484 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f6dd5dc0-68b9-48eb-9d19-5d225dc02ac6', 'name': '', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'network_id': '2207f983-427f-43e4-9408-1947306d980d', 'ip_version': 4, 'cidr': '61.20.41.0/26', 'allocation_pools': [{'start': '61.20.41.2', 'end': '61.20.41.62'}], 'gateway_ip': '61.20.41.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '61.20.41.2', 'tags': [], 'project_id': '543ec5972f7e46dc8804897564058067'}}
2025-08-02 23:58:25,512 - INFO - Created VN ctest-right_vn-15654945
2025-08-02 23:58:25,533 - DEBUG - VN ctest-right_vn-15654945 UUID is 2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,624 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:25,631 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:25,645 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:25,772 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,788 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,799 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:25,808 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:25,817 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/436927c2-d776-437d-ac24-a9cdd03126b2
2025-08-02 23:58:25,826 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:25,826 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,844 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:25,869 - INFO - Verified VN network id 14 for VN 2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,870 - INFO - Verifications in API Server for VN ctest-right_vn-15654945 passed
2025-08-02 23:58:25,870 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:25,882 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:25,893 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:25,903 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/436927c2-d776-437d-ac24-a9cdd03126b2
2025-08-02 23:58:25,926 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2452202864278193124', 'uuid-lslong': '10666803510972880909'}, 'enable': 'true', 'created': '2025-08-02T23:58:25', 'last-modified': '2025-08-02T23:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534554'}
2025-08-02 23:58:25,939 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:25,955 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2452202864278193124', 'uuid-lslong': '10666803510972880909'}, 'enable': 'true', 'created': '2025-08-02T23:58:25', 'last-modified': '2025-08-02T23:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.567953'}
2025-08-02 23:58:25,977 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:25,991 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2452202864278193124', 'uuid-lslong': '10666803510972880909'}, 'enable': 'true', 'created': '2025-08-02T23:58:25', 'last-modified': '2025-08-02T23:58:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.603675'}
2025-08-02 23:58:26,005 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:26,005 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-15654945 passed
2025-08-02 23:58:26,005 - DEBUG - ====Verifying policy data for ctest-right_vn-15654945 in API_Server ======
2025-08-02 23:58:26,006 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:26,015 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:26,030 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:26,155 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2207f983-427f-43e4-9408-1947306d980d
2025-08-02 23:58:26,167 - DEBUG - =>VN ctest-right_vn-15654945 has no policy to be verified
2025-08-02 23:58:26,167 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:26,167 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:26,167 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:26,187 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945?flat'}
2025-08-02 23:58:26,187 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:26,187 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:26,215 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945?flat'}
2025-08-02 23:58:26,215 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:26,215 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:26,240 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945?flat'}
2025-08-02 23:58:26,240 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-32165339:ctest-right_vn-15654945 is found in opserver
2025-08-02 23:58:26,273 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:26,291 - DEBUG - VRF ids for VN ctest-right_vn-15654945: {}
2025-08-02 23:58:26,670 - DEBUG - Response for create_network : {'network': {'id': '7c9ca5ae-8d44-4dac-a371-c825dfd21deb', 'name': 'ctest-trans_left_vn-30576132', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'project_id': '543ec5972f7e46dc8804897564058067', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-32165339', 'ctest-trans_left_vn-30576132'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:26,919 - DEBUG - Response for create_subnet : {'subnet': {'id': '316b294c-f801-492f-8bcd-2b2262dbdb2b', 'name': '', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'network_id': '7c9ca5ae-8d44-4dac-a371-c825dfd21deb', 'ip_version': 4, 'cidr': '39.221.3.64/26', 'allocation_pools': [{'start': '39.221.3.66', 'end': '39.221.3.126'}], 'gateway_ip': '39.221.3.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '39.221.3.66', 'tags': [], 'project_id': '543ec5972f7e46dc8804897564058067'}}
2025-08-02 23:58:26,945 - INFO - Created VN ctest-trans_left_vn-30576132
2025-08-02 23:58:26,956 - DEBUG - VN ctest-trans_left_vn-30576132 UUID is 7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,048 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:27,055 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:27,069 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:27,178 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,195 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,213 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:27,226 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:27,236 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/266a2936-8308-4ca0-bdb8-a1d1df3b6eda
2025-08-02 23:58:27,246 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-02 23:58:27,246 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,262 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:27,292 - INFO - Verified VN network id 15 for VN 7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,292 - INFO - Verifications in API Server for VN ctest-trans_left_vn-30576132 passed
2025-08-02 23:58:27,292 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,307 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:27,319 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:27,330 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/266a2936-8308-4ca0-bdb8-a1d1df3b6eda
2025-08-02 23:58:27,350 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8979233926182882732', 'uuid-lslong': '11777414565544992235'}, 'enable': 'true', 'created': '2025-08-02T23:58:26', 'last-modified': '2025-08-02T23:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486241'}
2025-08-02 23:58:27,359 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-02 23:58:27,374 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8979233926182882732', 'uuid-lslong': '11777414565544992235'}, 'enable': 'true', 'created': '2025-08-02T23:58:26', 'last-modified': '2025-08-02T23:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.502839'}
2025-08-02 23:58:27,387 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-02 23:58:27,403 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8979233926182882732', 'uuid-lslong': '11777414565544992235'}, 'enable': 'true', 'created': '2025-08-02T23:58:26', 'last-modified': '2025-08-02T23:58:26', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.533333'}
2025-08-02 23:58:27,425 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-02 23:58:27,425 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-30576132 passed
2025-08-02 23:58:27,425 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-30576132 in API_Server ======
2025-08-02 23:58:27,425 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:27,435 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:27,450 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:27,577 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/7c9ca5ae-8d44-4dac-a371-c825dfd21deb
2025-08-02 23:58:27,587 - DEBUG - =>VN ctest-trans_left_vn-30576132 has no policy to be verified
2025-08-02 23:58:27,587 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:27,588 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:27,588 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:27,603 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132?flat'}
2025-08-02 23:58:27,604 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:27,604 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:27,630 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132?flat'}
2025-08-02 23:58:27,630 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:27,630 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:27,658 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132?flat'}
2025-08-02 23:58:27,658 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-32165339:ctest-trans_left_vn-30576132 is found in opserver
2025-08-02 23:58:27,686 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:27,697 - DEBUG - VRF ids for VN ctest-trans_left_vn-30576132: {}
2025-08-02 23:58:28,082 - DEBUG - Response for create_network : {'network': {'id': 'f3ae9a44-7fc6-43ea-b6f0-d48527f80182', 'name': 'ctest-trans_right_vn-73449744', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'project_id': '543ec5972f7e46dc8804897564058067', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-32165339', 'ctest-trans_right_vn-73449744'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:28,360 - DEBUG - Response for create_subnet : {'subnet': {'id': '3b8bbe8d-443e-4144-992e-d957216c39ee', 'name': '', 'tenant_id': '543ec5972f7e46dc8804897564058067', 'network_id': 'f3ae9a44-7fc6-43ea-b6f0-d48527f80182', 'ip_version': 4, 'cidr': '99.50.20.128/26', 'allocation_pools': [{'start': '99.50.20.130', 'end': '99.50.20.190'}], 'gateway_ip': '99.50.20.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '99.50.20.130', 'tags': [], 'project_id': '543ec5972f7e46dc8804897564058067'}}
2025-08-02 23:58:28,395 - INFO - Created VN ctest-trans_right_vn-73449744
2025-08-02 23:58:28,411 - DEBUG - VN ctest-trans_right_vn-73449744 UUID is f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,496 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:28,503 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:28,517 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:28,636 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,649 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,660 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:28,669 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:28,677 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/cff85415-3e9e-4d02-a062-41a2009f1601
2025-08-02 23:58:28,686 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-02 23:58:28,686 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,698 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:28,717 - INFO - Verified VN network id 16 for VN f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,717 - INFO - Verifications in API Server for VN ctest-trans_right_vn-73449744 passed
2025-08-02 23:58:28,717 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,729 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:28,738 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:28,746 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/cff85415-3e9e-4d02-a062-41a2009f1601
2025-08-02 23:58:28,760 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17559141616156296170', 'uuid-lslong': '13182269777679745410'}, 'enable': 'true', 'created': '2025-08-02T23:58:28', 'last-modified': '2025-08-02T23:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.455770'}
2025-08-02 23:58:28,767 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-02 23:58:28,774 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17559141616156296170', 'uuid-lslong': '13182269777679745410'}, 'enable': 'true', 'created': '2025-08-02T23:58:28', 'last-modified': '2025-08-02T23:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472405'}
2025-08-02 23:58:28,785 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-02 23:58:28,795 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-32165339', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17559141616156296170', 'uuid-lslong': '13182269777679745410'}, 'enable': 'true', 'created': '2025-08-02T23:58:28', 'last-modified': '2025-08-02T23:58:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.488294'}
2025-08-02 23:58:28,807 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-02 23:58:28,807 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-73449744 passed
2025-08-02 23:58:28,808 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-73449744 in API_Server ======
2025-08-02 23:58:28,808 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:28,816 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:28,832 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:28,946 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f3ae9a44-7fc6-43ea-b6f0-d48527f80182
2025-08-02 23:58:28,958 - DEBUG - =>VN ctest-trans_right_vn-73449744 has no policy to be verified
2025-08-02 23:58:28,958 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:28,958 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:28,958 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:28,975 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744?flat'}
2025-08-02 23:58:28,975 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:28,975 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:29,001 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744?flat'}
2025-08-02 23:58:29,001 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:29,001 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:29,032 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744?flat'}
2025-08-02 23:58:29,032 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-32165339:ctest-trans_right_vn-73449744 is found in opserver
2025-08-02 23:58:29,057 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:29,069 - DEBUG - VRF ids for VN ctest-trans_right_vn-73449744: {}
2025-08-02 23:58:30,173 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-2)
2025-08-02 23:58:31,537 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-1)
2025-08-02 23:58:31,538 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-10230841']
2025-08-02 23:58:31,952 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-10230841'] created succesfully
2025-08-02 23:58:32,323 - DEBUG - Image by name tiny_trans_fw not found
2025-08-02 23:58:32,323 - DEBUG - Installing image tiny_trans_fw
2025-08-02 23:58:32,323 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-02 23:58:32,324 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-02 23:58:32,324 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-02 23:58:32,348 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-02 23:58:32,397 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-02 23:58:32,398 - DEBUG - Unzip image
2025-08-02 23:58:32,398 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-02 23:58:32,435 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-02 23:58:32,436 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-10230841']
2025-08-02 23:58:32,533 - INFO - Service template: ['default-domain', 'ctest-service_template_1-10230841'] deleted successfully.
2025-08-02 23:58:32,533 - INFO - Skip interface_detach for VM ctest-right_vm-18806938 in state BUILD
2025-08-02 23:58:32,533 - INFO - Deleting VM ctest-right_vm-18806938
2025-08-02 23:58:32,534 - ERROR - VM ctest-right_vm-18806938 has failed to come up
2025-08-02 23:58:32,534 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:32,534 - ERROR - VM ctest-right_vm-18806938 has failed to come up
2025-08-02 23:58:32,534 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:32,604 - INFO - Skip interface_detach for VM ctest-left_vm-66101664 in state BUILD
2025-08-02 23:58:32,604 - INFO - Deleting VM ctest-left_vm-66101664
2025-08-02 23:58:32,604 - ERROR - VM ctest-left_vm-66101664 has failed to come up
2025-08-02 23:58:32,605 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:32,605 - ERROR - VM ctest-left_vm-66101664 has failed to come up
2025-08-02 23:58:32,605 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:32,670 - INFO - Deleting VN ctest-trans_right_vn-73449744
2025-08-02 23:58:32,916 - DEBUG - Response for deleting network ()
2025-08-02 23:58:32,916 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8462240-a5da-40a3-83e7-092931d29378
2025-08-02 23:58:32,924 - DEBUG - Response Code: 404
2025-08-02 23:58:32,924 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:32,942 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:32,963 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:33,071 - INFO - Validated that VN ctest-trans_right_vn-73449744 is not found in API Server
2025-08-02 23:58:33,100 - DEBUG - VN ctest-trans_right_vn-73449744 is not present in Agent 10.0.0.48
2025-08-02 23:58:33,119 - DEBUG - VN ctest-trans_right_vn-73449744 is not present in Agent 10.0.0.49
2025-08-02 23:58:33,119 - INFO - Validated that VN ctest-trans_right_vn-73449744 is not in any agent
2025-08-02 23:58:33,184 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-73449744 info
2025-08-02 23:58:33,184 - INFO - Deleting VN ctest-trans_left_vn-30576132
2025-08-02 23:58:33,443 - DEBUG - Response for deleting network ()
2025-08-02 23:58:33,443 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1e517408-e3a5-4589-b115-a740e07ddd37
2025-08-02 23:58:33,468 - DEBUG - Response Code: 404
2025-08-02 23:58:33,468 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:33,490 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:33,518 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:33,630 - INFO - Validated that VN ctest-trans_left_vn-30576132 is not found in API Server
2025-08-02 23:58:33,669 - DEBUG - VN ctest-trans_left_vn-30576132 is not present in Agent 10.0.0.48
2025-08-02 23:58:33,685 - DEBUG - VN ctest-trans_left_vn-30576132 is not present in Agent 10.0.0.49
2025-08-02 23:58:33,685 - INFO - Validated that VN ctest-trans_left_vn-30576132 is not in any agent
2025-08-02 23:58:33,741 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-30576132 info
2025-08-02 23:58:33,741 - INFO - Deleting VN ctest-right_vn-15654945
2025-08-02 23:58:33,799 - DEBUG - VN 2207f983-427f-43e4-9408-1947306d980d still in use: Unable to complete operation on network 2207f983-427f-43e4-9408-1947306d980d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ff462538-6a5e-4626-afc2-22c6296df683']
2025-08-02 23:58:33,799 - WARNING - Deleting VN ctest-right_vn-15654945 failed..Will retry
2025-08-02 23:58:36,066 - DEBUG - Response for deleting network ()
2025-08-02 23:58:36,066 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/7e2fa918-6a3e-4ffb-bfd3-d915db5c2e12
2025-08-02 23:58:36,073 - DEBUG - Response Code: 404
2025-08-02 23:58:36,073 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:36,080 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:36,108 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:36,230 - INFO - Validated that VN ctest-right_vn-15654945 is not found in API Server
2025-08-02 23:58:36,244 - DEBUG - VN ctest-right_vn-15654945 is not present in Agent 10.0.0.48
2025-08-02 23:58:36,255 - DEBUG - VN ctest-right_vn-15654945 is not present in Agent 10.0.0.49
2025-08-02 23:58:36,255 - INFO - Validated that VN ctest-right_vn-15654945 is not in any agent
2025-08-02 23:58:36,290 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-15654945 info
2025-08-02 23:58:36,290 - INFO - Deleting VN ctest-left_vn-11501546
2025-08-02 23:58:36,543 - DEBUG - Response for deleting network ()
2025-08-02 23:58:36,543 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/e9ef3250-41dc-4f4f-8526-f006f6fa6f75
2025-08-02 23:58:36,549 - DEBUG - Response Code: 404
2025-08-02 23:58:36,549 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:36,563 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:36,584 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:36,688 - INFO - Validated that VN ctest-left_vn-11501546 is not found in API Server
2025-08-02 23:58:36,700 - DEBUG - VN ctest-left_vn-11501546 is not present in Agent 10.0.0.48
2025-08-02 23:58:36,712 - DEBUG - VN ctest-left_vn-11501546 is not present in Agent 10.0.0.49
2025-08-02 23:58:36,712 - INFO - Validated that VN ctest-left_vn-11501546 is not in any agent
2025-08-02 23:58:36,757 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-11501546 info
2025-08-02 23:58:36,758 - INFO - Deleting VN ctest-mgmt_vn-02703751
2025-08-02 23:58:36,936 - DEBUG - Response for deleting network ()
2025-08-02 23:58:36,936 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/60f70dc8-036e-49b5-90e2-b36bb36616ae
2025-08-02 23:58:36,943 - DEBUG - Response Code: 404
2025-08-02 23:58:36,943 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:36,948 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:37,017 - DEBUG - Requesting: http://10.0.0.38:8082/project/543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:37,131 - INFO - Validated that VN ctest-mgmt_vn-02703751 is not found in API Server
2025-08-02 23:58:37,147 - DEBUG - VN ctest-mgmt_vn-02703751 is not present in Agent 10.0.0.48
2025-08-02 23:58:37,160 - DEBUG - VN ctest-mgmt_vn-02703751 is not present in Agent 10.0.0.49
2025-08-02 23:58:37,160 - INFO - Validated that VN ctest-mgmt_vn-02703751 is not in any agent
2025-08-02 23:58:37,214 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-02703751 info
2025-08-02 23:58:38,599 - DEBUG - No XMPP flaps were noticed during the test
2025-08-02 23:58:38,599 - INFO -
2025-08-02 23:58:38,599 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2025-08-02 23:58:38,599 - INFO - --------------------------------------------------------------------------------
2025-08-02 23:58:39,438 - INFO - Deleted project: ctest-TestSvcRegr-32165339, ID : 543ec597-2f7e-46dc-8804-897564058067
2025-08-02 23:58:39,618 - INFO - Domain Default found not creating
2025-08-02 23:58:39,772 - INFO - Project ctest-TestSvcRegr-70049313 not found, creating it
2025-08-02 23:58:40,422 - INFO - Created Project:ctest-TestSvcRegr-70049313, ID : 553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:41,860 - INFO - ================================================================================
2025-08-02 23:58:41,860 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-02 23:58:43,220 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0', '10.20.0.14': '0'}, '10.0.0.49': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-08-02 23:58:43,220 - INFO - Initial checks done. Running the testcase now
2025-08-02 23:58:43,221 - INFO -
2025-08-02 23:58:44,010 - DEBUG - Response for create_network : {'network': {'id': 'bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829', 'name': 'ctest-mgmt_vn-66554607', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'ctest-mgmt_vn-66554607'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:44,300 - DEBUG - Response for create_subnet : {'subnet': {'id': '5736d221-436a-427a-a7f6-50cd14f81160', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': 'bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829', 'ip_version': 4, 'cidr': '112.235.22.128/26', 'allocation_pools': [{'start': '112.235.22.130', 'end': '112.235.22.190'}], 'gateway_ip': '112.235.22.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.235.22.130', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:58:44,331 - INFO - Created VN ctest-mgmt_vn-66554607
2025-08-02 23:58:44,348 - DEBUG - VN ctest-mgmt_vn-66554607 UUID is bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:44,432 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:44,741 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:44,774 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:44,826 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:44,922 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:44,972 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:44,988 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:44,999 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:45,008 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e2574b4b-3abc-481b-b52d-e9b315310ed9
2025-08-02 23:58:45,020 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:45,020 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:45,037 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:45,054 - INFO - Verified VN network id 12 for VN bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:45,054 - INFO - Verifications in API Server for VN ctest-mgmt_vn-66554607 passed
2025-08-02 23:58:45,055 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:45,067 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:45,076 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:45,085 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e2574b4b-3abc-481b-b52d-e9b315310ed9
2025-08-02 23:58:45,101 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13452776759153806202', 'uuid-lslong': '11127055017654577193'}, 'enable': 'true', 'created': '2025-08-02T23:58:43', 'last-modified': '2025-08-02T23:58:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.891520'}
2025-08-02 23:58:45,109 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:45,117 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13452776759153806202', 'uuid-lslong': '11127055017654577193'}, 'enable': 'true', 'created': '2025-08-02T23:58:43', 'last-modified': '2025-08-02T23:58:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.918255'}
2025-08-02 23:58:45,124 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:45,135 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13452776759153806202', 'uuid-lslong': '11127055017654577193'}, 'enable': 'true', 'created': '2025-08-02T23:58:43', 'last-modified': '2025-08-02T23:58:44', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.920367'}
2025-08-02 23:58:45,153 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:58:45,153 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-66554607 passed
2025-08-02 23:58:45,154 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-66554607 in API_Server ======
2025-08-02 23:58:45,154 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:45,166 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:45,182 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:45,287 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/bab1dd2f-c3fe-4b7a-9a6b-3d95bb876829
2025-08-02 23:58:45,303 - DEBUG - =>VN ctest-mgmt_vn-66554607 has no policy to be verified
2025-08-02 23:58:45,303 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:45,303 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:45,303 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:45,631 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:45,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607?flat'}
2025-08-02 23:58:45,708 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:45,708 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:46,016 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:46,080 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607?flat'}
2025-08-02 23:58:46,081 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:46,081 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:46,378 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:46,471 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607?flat'}
2025-08-02 23:58:46,471 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-66554607 is found in opserver
2025-08-02 23:58:46,495 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:46,507 - DEBUG - VRF ids for VN ctest-mgmt_vn-66554607: {}
2025-08-02 23:58:46,926 - DEBUG - Response for create_network : {'network': {'id': '66a04b97-e380-4b3d-82e5-19a93f206743', 'name': 'service-left-vn-04096710', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'service-left-vn-04096710'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:47,252 - DEBUG - Response for create_subnet : {'subnet': {'id': '7bd0d9c8-38c1-4196-9412-479de8e7f81f', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': '66a04b97-e380-4b3d-82e5-19a93f206743', 'ip_version': 4, 'cidr': '27.23.47.192/26', 'allocation_pools': [{'start': '27.23.47.194', 'end': '27.23.47.254'}], 'gateway_ip': '27.23.47.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.23.47.194', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:58:47,277 - INFO - Created VN service-left-vn-04096710
2025-08-02 23:58:47,296 - DEBUG - VN service-left-vn-04096710 UUID is 66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,393 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:47,400 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:47,415 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:47,530 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,552 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,564 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:47,580 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:47,589 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d811a001-2538-44c7-a417-182b9db8ef19
2025-08-02 23:58:47,597 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:47,597 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,608 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:47,625 - INFO - Verified VN network id 13 for VN 66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,625 - INFO - Verifications in API Server for VN service-left-vn-04096710 passed
2025-08-02 23:58:47,626 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,639 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:47,649 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:47,659 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/d811a001-2538-44c7-a417-182b9db8ef19
2025-08-02 23:58:47,675 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7394993703871335229', 'uuid-lslong': '9431973209296627523'}, 'enable': 'true', 'created': '2025-08-02T23:58:46', 'last-modified': '2025-08-02T23:58:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.522540'}
2025-08-02 23:58:47,682 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:47,692 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7394993703871335229', 'uuid-lslong': '9431973209296627523'}, 'enable': 'true', 'created': '2025-08-02T23:58:46', 'last-modified': '2025-08-02T23:58:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.538391'}
2025-08-02 23:58:47,699 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:47,707 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7394993703871335229', 'uuid-lslong': '9431973209296627523'}, 'enable': 'true', 'created': '2025-08-02T23:58:46', 'last-modified': '2025-08-02T23:58:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.516663'}
2025-08-02 23:58:47,718 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:58:47,718 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-04096710 passed
2025-08-02 23:58:47,719 - DEBUG - ====Verifying policy data for service-left-vn-04096710 in API_Server ======
2025-08-02 23:58:47,719 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:47,727 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:47,748 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:47,867 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/66a04b97-e380-4b3d-82e5-19a93f206743
2025-08-02 23:58:47,881 - DEBUG - =>VN service-left-vn-04096710 has no policy to be verified
2025-08-02 23:58:47,881 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:47,881 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:47,881 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:47,949 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710?flat'}
2025-08-02 23:58:47,949 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:47,949 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:48,018 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710?flat'}
2025-08-02 23:58:48,018 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:48,018 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:48,086 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710?flat'}
2025-08-02 23:58:48,086 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:service-left-vn-04096710 is found in opserver
2025-08-02 23:58:48,109 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:48,121 - DEBUG - VRF ids for VN service-left-vn-04096710: {}
2025-08-02 23:58:48,532 - DEBUG - Response for create_network : {'network': {'id': '52f5053c-cd32-4e55-9582-59adcf02d7bd', 'name': 'service-right-vn-22902548', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'service-right-vn-22902548'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:58:48,809 - DEBUG - Response for create_subnet : {'subnet': {'id': '00dc9b66-5847-4b70-80dc-9139329f4612', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': '52f5053c-cd32-4e55-9582-59adcf02d7bd', 'ip_version': 4, 'cidr': '186.148.244.64/26', 'allocation_pools': [{'start': '186.148.244.66', 'end': '186.148.244.126'}], 'gateway_ip': '186.148.244.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '186.148.244.66', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:58:48,837 - INFO - Created VN service-right-vn-22902548
2025-08-02 23:58:48,849 - DEBUG - VN service-right-vn-22902548 UUID is 52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:48,950 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:48,959 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:48,976 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:49,089 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,117 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,129 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:49,142 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:49,153 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/fac415a6-0847-421c-87cf-ab6ce68e66c7
2025-08-02 23:58:49,163 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:49,163 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,176 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:49,196 - INFO - Verified VN network id 14 for VN 52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,196 - INFO - Verifications in API Server for VN service-right-vn-22902548 passed
2025-08-02 23:58:49,197 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,211 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:49,223 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:49,233 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/fac415a6-0847-421c-87cf-ab6ce68e66c7
2025-08-02 23:58:49,248 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5977689839103004245', 'uuid-lslong': '10773271861660932029'}, 'enable': 'true', 'created': '2025-08-02T23:58:48', 'last-modified': '2025-08-02T23:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479779'}
2025-08-02 23:58:49,257 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:49,265 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5977689839103004245', 'uuid-lslong': '10773271861660932029'}, 'enable': 'true', 'created': '2025-08-02T23:58:48', 'last-modified': '2025-08-02T23:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.498723'}
2025-08-02 23:58:49,274 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:49,282 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5977689839103004245', 'uuid-lslong': '10773271861660932029'}, 'enable': 'true', 'created': '2025-08-02T23:58:48', 'last-modified': '2025-08-02T23:58:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517225'}
2025-08-02 23:58:49,293 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:58:49,293 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-22902548 passed
2025-08-02 23:58:49,293 - DEBUG - ====Verifying policy data for service-right-vn-22902548 in API_Server ======
2025-08-02 23:58:49,293 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:49,302 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:49,318 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:49,439 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/52f5053c-cd32-4e55-9582-59adcf02d7bd
2025-08-02 23:58:49,458 - DEBUG - =>VN service-right-vn-22902548 has no policy to be verified
2025-08-02 23:58:49,458 - DEBUG - Verifying the vn in opserver
2025-08-02 23:58:49,458 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548 virtual network link through opserver 10.0.0.38
2025-08-02 23:58:49,459 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:58:49,536 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548?flat'}
2025-08-02 23:58:49,536 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548 virtual network link through opserver 10.0.0.254
2025-08-02 23:58:49,536 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:58:49,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548?flat'}
2025-08-02 23:58:49,601 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548 virtual network link through opserver 10.0.0.50
2025-08-02 23:58:49,601 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:58:49,696 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548?flat'}
2025-08-02 23:58:49,696 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:service-right-vn-22902548 is found in opserver
2025-08-02 23:58:49,729 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:58:49,750 - DEBUG - VRF ids for VN service-right-vn-22902548: {}
2025-08-02 23:58:49,846 - DEBUG - Services list from nova: [, , , ]
2025-08-02 23:58:51,076 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-2)
2025-08-02 23:58:52,653 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-1)
2025-08-02 23:58:52,654 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-70431389']
2025-08-02 23:58:53,044 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-70431389'] created succesfully
2025-08-02 23:58:53,449 - DEBUG - Image by name tiny_nat_fw not found
2025-08-02 23:58:53,449 - DEBUG - Installing image tiny_nat_fw
2025-08-02 23:58:53,449 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-02 23:58:53,450 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:53,450 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:53,450 - DEBUG - Unzip image
2025-08-02 23:58:53,450 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:58:53,477 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-02 23:58:53,477 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-70431389']
2025-08-02 23:58:53,589 - INFO - Service template: ['default-domain', 'ctest-service_template_1-70431389'] deleted successfully.
2025-08-02 23:58:53,589 - INFO - Skip interface_detach for VM ctest-right_vm-41807513 in state BUILD
2025-08-02 23:58:53,589 - INFO - Deleting VM ctest-right_vm-41807513
2025-08-02 23:58:53,630 - ERROR - VM ctest-right_vm-41807513 has failed to come up
2025-08-02 23:58:53,630 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:53,630 - ERROR - VM ctest-right_vm-41807513 has failed to come up
2025-08-02 23:58:53,631 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:53,758 - INFO - Skip interface_detach for VM ctest-left_vm-68915528 in state BUILD
2025-08-02 23:58:53,758 - INFO - Deleting VM ctest-left_vm-68915528
2025-08-02 23:58:53,758 - ERROR - VM ctest-left_vm-68915528 has failed to come up
2025-08-02 23:58:53,758 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:53,762 - ERROR - VM ctest-left_vm-68915528 has failed to come up
2025-08-02 23:58:53,763 - ERROR - Nova failed to get host of the VM
2025-08-02 23:58:53,872 - INFO - Deleting VN service-right-vn-22902548
2025-08-02 23:58:53,943 - DEBUG - VN 52f5053c-cd32-4e55-9582-59adcf02d7bd still in use: Unable to complete operation on network 52f5053c-cd32-4e55-9582-59adcf02d7bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-71f1d670-b18f-48b9-9f45-114e9de38c81']
2025-08-02 23:58:53,944 - WARNING - Deleting VN service-right-vn-22902548 failed..Will retry
2025-08-02 23:58:56,200 - DEBUG - Response for deleting network ()
2025-08-02 23:58:56,200 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/f8a30d07-4130-4c35-b1f7-d5eca5c45bc3
2025-08-02 23:58:56,206 - DEBUG - Response Code: 404
2025-08-02 23:58:56,207 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:56,222 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:56,260 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:56,362 - INFO - Validated that VN service-right-vn-22902548 is not found in API Server
2025-08-02 23:58:56,376 - DEBUG - VN service-right-vn-22902548 is not present in Agent 10.0.0.48
2025-08-02 23:58:56,387 - DEBUG - VN service-right-vn-22902548 is not present in Agent 10.0.0.49
2025-08-02 23:58:56,387 - INFO - Validated that VN service-right-vn-22902548 is not in any agent
2025-08-02 23:58:56,425 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-22902548 info
2025-08-02 23:58:56,425 - INFO - Deleting VN service-left-vn-04096710
2025-08-02 23:58:56,636 - DEBUG - Response for deleting network ()
2025-08-02 23:58:56,636 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/ae9d6ad4-64d2-411a-92db-e8647ae68b91
2025-08-02 23:58:56,654 - DEBUG - Response Code: 404
2025-08-02 23:58:56,654 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:56,666 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:56,680 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:56,785 - INFO - Validated that VN service-left-vn-04096710 is not found in API Server
2025-08-02 23:58:56,798 - DEBUG - VN service-left-vn-04096710 is not present in Agent 10.0.0.48
2025-08-02 23:58:56,809 - DEBUG - VN service-left-vn-04096710 is not present in Agent 10.0.0.49
2025-08-02 23:58:56,809 - INFO - Validated that VN service-left-vn-04096710 is not in any agent
2025-08-02 23:58:56,854 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-04096710 info
2025-08-02 23:58:56,854 - INFO - Deleting VN ctest-mgmt_vn-66554607
2025-08-02 23:58:57,044 - DEBUG - Response for deleting network ()
2025-08-02 23:58:57,044 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/c2dd2d7b-64b6-4b7a-b422-a5355381d7b1
2025-08-02 23:58:57,053 - DEBUG - Response Code: 404
2025-08-02 23:58:57,053 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:58:57,066 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:58:57,080 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:58:57,190 - INFO - Validated that VN ctest-mgmt_vn-66554607 is not found in API Server
2025-08-02 23:58:57,206 - DEBUG - VN ctest-mgmt_vn-66554607 is not present in Agent 10.0.0.48
2025-08-02 23:58:57,219 - DEBUG - VN ctest-mgmt_vn-66554607 is not present in Agent 10.0.0.49
2025-08-02 23:58:57,219 - INFO - Validated that VN ctest-mgmt_vn-66554607 is not in any agent
2025-08-02 23:58:57,267 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-66554607 info
2025-08-02 23:58:58,641 - DEBUG - No XMPP flaps were noticed during the test
2025-08-02 23:58:58,641 - INFO -
2025-08-02 23:58:58,641 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:17]
2025-08-02 23:58:58,641 - INFO - --------------------------------------------------------------------------------
2025-08-02 23:58:58,647 - INFO - ================================================================================
2025-08-02 23:58:58,647 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-02 23:59:00,030 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0', '10.20.0.14': '0'}, '10.0.0.49': {'10.20.0.17': '0', '10.20.0.14': '0'}} with
2025-08-02 23:59:00,030 - INFO - Initial checks done. Running the testcase now
2025-08-02 23:59:00,030 - INFO -
2025-08-02 23:59:00,441 - DEBUG - Response for create_network : {'network': {'id': '8bca6cf1-cbd3-45db-bf8d-0faadc8d4923', 'name': 'ctest-mgmt_vn-03391660', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'ctest-mgmt_vn-03391660'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:59:00,667 - DEBUG - Response for create_subnet : {'subnet': {'id': '66866f2b-bd2b-4e0b-be3a-a979e17a01c0', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': '8bca6cf1-cbd3-45db-bf8d-0faadc8d4923', 'ip_version': 4, 'cidr': '182.80.113.192/26', 'allocation_pools': [{'start': '182.80.113.194', 'end': '182.80.113.254'}], 'gateway_ip': '182.80.113.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.80.113.194', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:59:00,694 - INFO - Created VN ctest-mgmt_vn-03391660
2025-08-02 23:59:00,708 - DEBUG - VN ctest-mgmt_vn-03391660 UUID is 8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:00,802 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:00,810 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:00,831 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:00,935 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:00,961 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:00,990 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:01,013 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:01,023 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5fe0e6b4-6f89-4696-8390-b415d65e6e39
2025-08-02 23:59:01,033 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:59:01,033 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:01,048 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:01,078 - INFO - Verified VN network id 12 for VN 8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:01,079 - INFO - Verifications in API Server for VN ctest-mgmt_vn-03391660 passed
2025-08-02 23:59:01,079 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:01,099 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:01,110 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:01,205 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5fe0e6b4-6f89-4696-8390-b415d65e6e39
2025-08-02 23:59:01,225 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10072983302330074587', 'uuid-lslong': '13802705659479542051'}, 'enable': 'true', 'created': '2025-08-02T23:59:00', 'last-modified': '2025-08-02T23:59:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.617849'}
2025-08-02 23:59:01,232 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:59:01,241 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10072983302330074587', 'uuid-lslong': '13802705659479542051'}, 'enable': 'true', 'created': '2025-08-02T23:59:00', 'last-modified': '2025-08-02T23:59:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.637756'}
2025-08-02 23:59:01,257 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:59:01,272 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10072983302330074587', 'uuid-lslong': '13802705659479542051'}, 'enable': 'true', 'created': '2025-08-02T23:59:00', 'last-modified': '2025-08-02T23:59:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.665242'}
2025-08-02 23:59:01,294 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-02 23:59:01,294 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-03391660 passed
2025-08-02 23:59:01,295 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-03391660 in API_Server ======
2025-08-02 23:59:01,295 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:01,306 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:01,323 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:01,447 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/8bca6cf1-cbd3-45db-bf8d-0faadc8d4923
2025-08-02 23:59:01,464 - DEBUG - =>VN ctest-mgmt_vn-03391660 has no policy to be verified
2025-08-02 23:59:01,464 - DEBUG - Verifying the vn in opserver
2025-08-02 23:59:01,465 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660 virtual network link through opserver 10.0.0.38
2025-08-02 23:59:01,465 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:59:01,481 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660?flat'}
2025-08-02 23:59:01,481 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660 virtual network link through opserver 10.0.0.254
2025-08-02 23:59:01,481 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:59:01,508 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660?flat'}
2025-08-02 23:59:01,508 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660 virtual network link through opserver 10.0.0.50
2025-08-02 23:59:01,508 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:59:01,536 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660?flat'}
2025-08-02 23:59:01,536 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:ctest-mgmt_vn-03391660 is found in opserver
2025-08-02 23:59:01,564 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:59:01,577 - DEBUG - VRF ids for VN ctest-mgmt_vn-03391660: {}
2025-08-02 23:59:01,993 - DEBUG - Response for create_network : {'network': {'id': '0714e863-46c5-4a65-bc96-504754a32f6a', 'name': 'ctest-left_vn-99305085', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'ctest-left_vn-99305085'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:59:02,277 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e06e1deb-8fc4-4b5e-acdf-c95963ed837c', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': '0714e863-46c5-4a65-bc96-504754a32f6a', 'ip_version': 4, 'cidr': '166.191.149.192/26', 'allocation_pools': [{'start': '166.191.149.194', 'end': '166.191.149.254'}], 'gateway_ip': '166.191.149.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.191.149.194', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:59:02,302 - INFO - Created VN ctest-left_vn-99305085
2025-08-02 23:59:02,321 - DEBUG - VN ctest-left_vn-99305085 UUID is 0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,408 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:02,415 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:02,429 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:02,540 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,563 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,573 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:02,584 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:02,593 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5111a402-55cf-4aaa-9087-92ae7fe2802f
2025-08-02 23:59:02,601 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:59:02,601 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,611 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:02,632 - INFO - Verified VN network id 13 for VN 0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,632 - INFO - Verifications in API Server for VN ctest-left_vn-99305085 passed
2025-08-02 23:59:02,632 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,643 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:02,651 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:02,660 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5111a402-55cf-4aaa-9087-92ae7fe2802f
2025-08-02 23:59:02,673 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '510288170886449765', 'uuid-lslong': '13589137192929931114'}, 'enable': 'true', 'created': '2025-08-02T23:59:01', 'last-modified': '2025-08-02T23:59:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.461909'}
2025-08-02 23:59:02,680 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:59:02,692 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '510288170886449765', 'uuid-lslong': '13589137192929931114'}, 'enable': 'true', 'created': '2025-08-02T23:59:01', 'last-modified': '2025-08-02T23:59:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486767'}
2025-08-02 23:59:02,703 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:59:02,716 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '510288170886449765', 'uuid-lslong': '13589137192929931114'}, 'enable': 'true', 'created': '2025-08-02T23:59:01', 'last-modified': '2025-08-02T23:59:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.509364'}
2025-08-02 23:59:02,730 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-02 23:59:02,730 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-99305085 passed
2025-08-02 23:59:02,730 - DEBUG - ====Verifying policy data for ctest-left_vn-99305085 in API_Server ======
2025-08-02 23:59:02,730 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:02,738 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:02,752 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:02,879 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/0714e863-46c5-4a65-bc96-504754a32f6a
2025-08-02 23:59:02,898 - DEBUG - =>VN ctest-left_vn-99305085 has no policy to be verified
2025-08-02 23:59:02,898 - DEBUG - Verifying the vn in opserver
2025-08-02 23:59:02,898 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085 virtual network link through opserver 10.0.0.38
2025-08-02 23:59:02,898 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:59:02,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085?flat'}
2025-08-02 23:59:02,916 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085 virtual network link through opserver 10.0.0.254
2025-08-02 23:59:02,916 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:59:02,936 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085?flat'}
2025-08-02 23:59:02,936 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085 virtual network link through opserver 10.0.0.50
2025-08-02 23:59:02,936 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:59:02,959 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085?flat'}
2025-08-02 23:59:02,959 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:ctest-left_vn-99305085 is found in opserver
2025-08-02 23:59:02,983 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:59:02,997 - DEBUG - VRF ids for VN ctest-left_vn-99305085: {}
2025-08-02 23:59:03,358 - DEBUG - Response for create_network : {'network': {'id': '589b02c6-38b8-4fd6-976b-f895a4ce55e1', 'name': 'ctest-right_vn-57714204', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'project_id': '553482bb9546417fb7630e15b7e833ca', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-70049313', 'ctest-right_vn-57714204'], 'port_security_enabled': True, 'description': ''}}
2025-08-02 23:59:03,615 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e5a91ba9-7462-42e6-8255-46a6ceb9c308', 'name': '', 'tenant_id': '553482bb9546417fb7630e15b7e833ca', 'network_id': '589b02c6-38b8-4fd6-976b-f895a4ce55e1', 'ip_version': 4, 'cidr': '104.172.82.192/26', 'allocation_pools': [{'start': '104.172.82.194', 'end': '104.172.82.254'}], 'gateway_ip': '104.172.82.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.172.82.194', 'tags': [], 'project_id': '553482bb9546417fb7630e15b7e833ca'}}
2025-08-02 23:59:03,643 - INFO - Created VN ctest-right_vn-57714204
2025-08-02 23:59:03,657 - DEBUG - VN ctest-right_vn-57714204 UUID is 589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,762 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:03,769 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:03,783 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:03,888 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,906 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,916 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:03,926 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:03,934 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/bbbfb983-6332-423a-a423-0b915100b5d4
2025-08-02 23:59:03,945 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:59:03,945 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,955 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:03,975 - INFO - Verified VN network id 14 for VN 589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,975 - INFO - Verifications in API Server for VN ctest-right_vn-57714204 passed
2025-08-02 23:59:03,975 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:03,989 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:03,998 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:04,006 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/bbbfb983-6332-423a-a423-0b915100b5d4
2025-08-02 23:59:04,021 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6384699947106193366', 'uuid-lslong': '10911087843833959905'}, 'enable': 'true', 'created': '2025-08-02T23:59:03', 'last-modified': '2025-08-02T23:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.485523'}
2025-08-02 23:59:04,028 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:59:04,036 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6384699947106193366', 'uuid-lslong': '10911087843833959905'}, 'enable': 'true', 'created': '2025-08-02T23:59:03', 'last-modified': '2025-08-02T23:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.475069'}
2025-08-02 23:59:04,049 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:59:04,059 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-70049313', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6384699947106193366', 'uuid-lslong': '10911087843833959905'}, 'enable': 'true', 'created': '2025-08-02T23:59:03', 'last-modified': '2025-08-02T23:59:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520697'}
2025-08-02 23:59:04,068 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-02 23:59:04,069 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-57714204 passed
2025-08-02 23:59:04,069 - DEBUG - ====Verifying policy data for ctest-right_vn-57714204 in API_Server ======
2025-08-02 23:59:04,069 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:04,078 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:04,097 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:04,221 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/589b02c6-38b8-4fd6-976b-f895a4ce55e1
2025-08-02 23:59:04,233 - DEBUG - =>VN ctest-right_vn-57714204 has no policy to be verified
2025-08-02 23:59:04,233 - DEBUG - Verifying the vn in opserver
2025-08-02 23:59:04,233 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204 virtual network link through opserver 10.0.0.38
2025-08-02 23:59:04,233 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-02 23:59:04,252 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204?flat'}
2025-08-02 23:59:04,253 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204 virtual network link through opserver 10.0.0.254
2025-08-02 23:59:04,253 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-02 23:59:04,277 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204?flat'}
2025-08-02 23:59:04,277 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204 virtual network link through opserver 10.0.0.50
2025-08-02 23:59:04,278 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-02 23:59:04,307 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204?flat'}
2025-08-02 23:59:04,308 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-70049313:ctest-right_vn-57714204 is found in opserver
2025-08-02 23:59:04,334 - DEBUG - Do not have enough data to verify VN in agent
2025-08-02 23:59:04,350 - DEBUG - VRF ids for VN ctest-right_vn-57714204: {}
2025-08-02 23:59:05,440 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-1)
2025-08-02 23:59:06,805 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-2)
2025-08-02 23:59:06,806 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-04231443']
2025-08-02 23:59:07,197 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-04231443'] created succesfully
2025-08-02 23:59:07,600 - DEBUG - Image by name tiny_nat_fw not found
2025-08-02 23:59:07,600 - DEBUG - Installing image tiny_nat_fw
2025-08-02 23:59:07,600 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-02 23:59:07,601 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:59:07,601 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:59:07,601 - DEBUG - Unzip image
2025-08-02 23:59:07,601 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-02 23:59:07,641 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-02 23:59:07,641 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-04231443']
2025-08-02 23:59:07,765 - INFO - Service template: ['default-domain', 'ctest-service_template_1-04231443'] deleted successfully.
2025-08-02 23:59:07,766 - INFO - Skip interface_detach for VM ctest-right_vm-87599423 in state BUILD
2025-08-02 23:59:07,766 - INFO - Deleting VM ctest-right_vm-87599423
2025-08-02 23:59:07,766 - ERROR - VM ctest-right_vm-87599423 has failed to come up
2025-08-02 23:59:07,766 - ERROR - Nova failed to get host of the VM
2025-08-02 23:59:07,766 - ERROR - VM ctest-right_vm-87599423 has failed to come up
2025-08-02 23:59:07,766 - ERROR - Nova failed to get host of the VM
2025-08-02 23:59:07,925 - INFO - Skip interface_detach for VM ctest-left_vm-25751785 in state BUILD
2025-08-02 23:59:07,927 - INFO - Deleting VM ctest-left_vm-25751785
2025-08-02 23:59:07,927 - ERROR - VM ctest-left_vm-25751785 has failed to come up
2025-08-02 23:59:07,927 - ERROR - Nova failed to get host of the VM
2025-08-02 23:59:07,928 - ERROR - VM ctest-left_vm-25751785 has failed to come up
2025-08-02 23:59:07,929 - ERROR - Nova failed to get host of the VM
2025-08-02 23:59:08,030 - INFO - Deleting VN ctest-right_vn-57714204
2025-08-02 23:59:08,124 - DEBUG - VN 589b02c6-38b8-4fd6-976b-f895a4ce55e1 still in use: Unable to complete operation on network 589b02c6-38b8-4fd6-976b-f895a4ce55e1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f4202b52-8c76-4645-af2b-2d2e07d61963']
2025-08-02 23:59:08,124 - WARNING - Deleting VN ctest-right_vn-57714204 failed..Will retry
2025-08-02 23:59:10,316 - DEBUG - Response for deleting network ()
2025-08-02 23:59:10,316 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/97ff9891-6167-4fb4-ab94-dcb7cf904301
2025-08-02 23:59:10,325 - DEBUG - Response Code: 404
2025-08-02 23:59:10,326 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:10,345 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:10,388 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:10,499 - INFO - Validated that VN ctest-right_vn-57714204 is not found in API Server
2025-08-02 23:59:10,511 - DEBUG - VN ctest-right_vn-57714204 is not present in Agent 10.0.0.48
2025-08-02 23:59:10,523 - DEBUG - VN ctest-right_vn-57714204 is not present in Agent 10.0.0.49
2025-08-02 23:59:10,523 - INFO - Validated that VN ctest-right_vn-57714204 is not in any agent
2025-08-02 23:59:10,564 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-57714204 info
2025-08-02 23:59:10,565 - INFO - Deleting VN ctest-left_vn-99305085
2025-08-02 23:59:10,810 - DEBUG - Response for deleting network ()
2025-08-02 23:59:10,810 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/aee5c15c-afb7-421a-9aef-19449dde1c8a
2025-08-02 23:59:10,819 - DEBUG - Response Code: 404
2025-08-02 23:59:10,819 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:10,838 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:10,865 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:10,992 - INFO - Validated that VN ctest-left_vn-99305085 is not found in API Server
2025-08-02 23:59:11,008 - DEBUG - VN ctest-left_vn-99305085 is not present in Agent 10.0.0.48
2025-08-02 23:59:11,024 - DEBUG - VN ctest-left_vn-99305085 is not present in Agent 10.0.0.49
2025-08-02 23:59:11,024 - INFO - Validated that VN ctest-left_vn-99305085 is not in any agent
2025-08-02 23:59:11,087 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-99305085 info
2025-08-02 23:59:11,087 - INFO - Deleting VN ctest-mgmt_vn-03391660
2025-08-02 23:59:11,317 - DEBUG - Response for deleting network ()
2025-08-02 23:59:11,317 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/a8c7f3e9-6acf-4c04-80e1-c6953b487e8e
2025-08-02 23:59:11,324 - DEBUG - Response Code: 404
2025-08-02 23:59:11,325 - DEBUG - Requesting: http://10.0.0.38:8082/domains
2025-08-02 23:59:11,333 - DEBUG - Requesting: http://10.0.0.38:8082/domain/e48a4202-dd3d-4b87-9406-48f7b2154028
2025-08-02 23:59:11,357 - DEBUG - Requesting: http://10.0.0.38:8082/project/553482bb-9546-417f-b763-0e15b7e833ca
2025-08-02 23:59:11,467 - INFO - Validated that VN ctest-mgmt_vn-03391660 is not found in API Server
2025-08-02 23:59:11,479 - DEBUG - VN ctest-mgmt_vn-03391660 is not present in Agent 10.0.0.48
2025-08-02 23:59:11,491 - DEBUG - VN ctest-mgmt_vn-03391660 is not present in Agent 10.0.0.49
2025-08-02 23:59:11,491 - INFO - Validated that VN ctest-mgmt_vn-03391660 is not in any agent
2025-08-02 23:59:11,541 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-03391660 info
2025-08-02 23:59:12,891 - DEBUG - No XMPP flaps were noticed during the test
2025-08-02 23:59:12,891 - INFO -
2025-08-02 23:59:12,891 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-08-02 23:59:12,892 - INFO - --------------------------------------------------------------------------------
2025-08-02 23:59:13,740 - INFO - Deleted project: ctest-TestSvcRegr-70049313, ID : 553482bb-9546-417f-b763-0e15b7e833ca