]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-1)
2025-09-08 03:17:44,067 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-20967483']
2025-09-08 03:17:44,374 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-20967483'] created succesfully
2025-09-08 03:17:44,815 - DEBUG - Image by name tiny_nat_fw not found
2025-09-08 03:17:44,815 - DEBUG - Installing image tiny_nat_fw
2025-09-08 03:17:44,815 - 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-09-08 03:17:44,815 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-08 03:17:44,815 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-08 03:17:44,837 - 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-09-08 03:17:45,105 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:17:45,106 - DEBUG - Unzip image
2025-09-08 03:17:45,106 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:17:45,129 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-08 03:17:45,130 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-20967483']
2025-09-08 03:17:45,231 - INFO - Service template: ['default-domain', 'ctest-service_template_1-20967483'] deleted successfully.
2025-09-08 03:17:45,231 - INFO - Skip interface_detach for VM ctest-right_vm-64310847 in state BUILD
2025-09-08 03:17:45,231 - INFO - Deleting VM ctest-right_vm-64310847
2025-09-08 03:17:45,261 - ERROR - VM ctest-right_vm-64310847 has failed to come up
2025-09-08 03:17:45,261 - ERROR - Nova failed to get host of the VM
2025-09-08 03:17:45,261 - ERROR - VM ctest-right_vm-64310847 has failed to come up
2025-09-08 03:17:45,261 - ERROR - Nova failed to get host of the VM
2025-09-08 03:17:45,342 - INFO - Skip interface_detach for VM ctest-left_vm-99568201 in state BUILD
2025-09-08 03:17:45,342 - INFO - Deleting VM ctest-left_vm-99568201
2025-09-08 03:17:45,342 - ERROR - VM ctest-left_vm-99568201 has failed to come up
2025-09-08 03:17:45,343 - ERROR - Nova failed to get host of the VM
2025-09-08 03:17:45,343 - ERROR - VM ctest-left_vm-99568201 has failed to come up
2025-09-08 03:17:45,343 - ERROR - Nova failed to get host of the VM
2025-09-08 03:17:45,411 - INFO - Deleting VN ctest-right_vn-99299796
2025-09-08 03:17:45,463 - DEBUG - VN e1a610b4-2edd-4f91-b8eb-b7d37da0392c still in use: Unable to complete operation on network e1a610b4-2edd-4f91-b8eb-b7d37da0392c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-924d6fe8-0a16-47da-9f7b-4df308bb3f67']
2025-09-08 03:17:45,463 - WARNING - Deleting VN ctest-right_vn-99299796 failed..Will retry
2025-09-08 03:17:47,649 - DEBUG - Response for deleting network ()
2025-09-08 03:17:47,649 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/206cf070-4585-4057-af28-f45746baacd1
2025-09-08 03:17:47,660 - DEBUG - Response Code: 404
2025-09-08 03:17:47,660 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:47,673 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:47,710 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:47,800 - INFO - Validated that VN ctest-right_vn-99299796 is not found in API Server
2025-09-08 03:17:47,826 - DEBUG - VN ctest-right_vn-99299796 is not present in Agent 10.0.0.65
2025-09-08 03:17:47,838 - DEBUG - VN ctest-right_vn-99299796 is not present in Agent 10.0.0.23
2025-09-08 03:17:47,839 - INFO - Validated that VN ctest-right_vn-99299796 is not in any agent
2025-09-08 03:17:47,876 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-99299796 info
2025-09-08 03:17:47,877 - INFO - Deleting VN ctest-left_vn-39767468
2025-09-08 03:17:48,060 - DEBUG - Response for deleting network ()
2025-09-08 03:17:48,060 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/7d664058-62e6-4b74-b6d7-97f2eb1f961c
2025-09-08 03:17:48,073 - DEBUG - Response Code: 404
2025-09-08 03:17:48,073 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:48,082 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:48,095 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:48,197 - INFO - Validated that VN ctest-left_vn-39767468 is not found in API Server
2025-09-08 03:17:48,231 - DEBUG - VN ctest-left_vn-39767468 is not present in Agent 10.0.0.65
2025-09-08 03:17:48,252 - DEBUG - VN ctest-left_vn-39767468 is not present in Agent 10.0.0.23
2025-09-08 03:17:48,252 - INFO - Validated that VN ctest-left_vn-39767468 is not in any agent
2025-09-08 03:17:48,305 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-39767468 info
2025-09-08 03:17:48,305 - INFO - Deleting VN ctest-mgmt_vn-49921820
2025-09-08 03:17:48,493 - DEBUG - Response for deleting network ()
2025-09-08 03:17:48,493 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/41f07b5f-b9ef-4eab-9ff4-e713ae8396ff
2025-09-08 03:17:48,498 - DEBUG - Response Code: 404
2025-09-08 03:17:48,498 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:48,503 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:48,563 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:48,661 - INFO - Validated that VN ctest-mgmt_vn-49921820 is not found in API Server
2025-09-08 03:17:48,681 - DEBUG - VN ctest-mgmt_vn-49921820 is not present in Agent 10.0.0.65
2025-09-08 03:17:48,703 - DEBUG - VN ctest-mgmt_vn-49921820 is not present in Agent 10.0.0.23
2025-09-08 03:17:48,703 - INFO - Validated that VN ctest-mgmt_vn-49921820 is not in any agent
2025-09-08 03:17:48,741 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-49921820 info
2025-09-08 03:17:48,741 - INFO - Deleting VM ctest-vm-in-third-vn-72176246
2025-09-08 03:17:48,816 - INFO - Deleting VN ctest-third-vn-45272563
2025-09-08 03:17:48,865 - DEBUG - VN 3ea0307d-3c67-48a5-86c9-1ef613aaf276 still in use: Unable to complete operation on network 3ea0307d-3c67-48a5-86c9-1ef613aaf276. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dfed8e9e-f8cd-4fb2-bbe1-3a68ba271e9d']
2025-09-08 03:17:48,866 - WARNING - Deleting VN ctest-third-vn-45272563 failed..Will retry
2025-09-08 03:17:51,017 - DEBUG - Response for deleting network ()
2025-09-08 03:17:52,411 - DEBUG - No XMPP flaps were noticed during the test
2025-09-08 03:17:52,411 - INFO -
2025-09-08 03:17:52,411 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2025-09-08 03:17:52,411 - INFO - --------------------------------------------------------------------------------
2025-09-08 03:17:52,415 - INFO - ================================================================================
2025-09-08 03:17:52,415 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-09-08 03:17:53,819 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.129': '0'}} with
2025-09-08 03:17:53,820 - INFO - Initial checks done. Running the testcase now
2025-09-08 03:17:53,820 - INFO -
2025-09-08 03:17:54,101 - DEBUG - Response for create_network : {'network': {'id': 'b918749b-1332-49a7-8a0e-94a91ea148f4', 'name': 'ctest-mgmt_vn-99787422', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'project_id': '1bb8aed0338a42dc88773ae245eab56a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87977112', 'ctest-mgmt_vn-99787422'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:17:54,387 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fb8fe26a-67c8-4e1c-8148-e46299a05680', 'name': '', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'network_id': 'b918749b-1332-49a7-8a0e-94a91ea148f4', 'ip_version': 4, 'cidr': '56.32.126.128/26', 'allocation_pools': [{'start': '56.32.126.130', 'end': '56.32.126.190'}], 'gateway_ip': '56.32.126.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.32.126.130', 'tags': [], 'project_id': '1bb8aed0338a42dc88773ae245eab56a'}}
2025-09-08 03:17:54,414 - INFO - Created VN ctest-mgmt_vn-99787422
2025-09-08 03:17:54,426 - DEBUG - VN ctest-mgmt_vn-99787422 UUID is b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,506 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:54,514 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:54,529 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:54,626 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,641 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,652 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:17:54,662 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:17:54,670 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/9a632933-a3c1-45b2-bb01-7dcdeba4b166
2025-09-08 03:17:54,680 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:17:54,680 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,693 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:17:54,715 - INFO - Verified VN network id 14 for VN b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,715 - INFO - Verifications in API Server for VN ctest-mgmt_vn-99787422 passed
2025-09-08 03:17:54,715 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,728 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:17:54,737 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:17:54,747 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/9a632933-a3c1-45b2-bb01-7dcdeba4b166
2025-09-08 03:17:54,772 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13337538505848539559', 'uuid-lslong': '9948052080992274676'}, 'enable': 'true', 'created': '2025-09-08T03:17:54', 'last-modified': '2025-09-08T03:17:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439899'}
2025-09-08 03:17:54,778 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:17:54,783 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13337538505848539559', 'uuid-lslong': '9948052080992274676'}, 'enable': 'true', 'created': '2025-09-08T03:17:54', 'last-modified': '2025-09-08T03:17:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.461383'}
2025-09-08 03:17:54,790 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:17:54,796 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13337538505848539559', 'uuid-lslong': '9948052080992274676'}, 'enable': 'true', 'created': '2025-09-08T03:17:54', 'last-modified': '2025-09-08T03:17:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471442'}
2025-09-08 03:17:54,804 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:17:54,804 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-99787422 passed
2025-09-08 03:17:54,804 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-99787422 in API_Server ======
2025-09-08 03:17:54,805 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:54,812 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:54,824 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:54,925 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/b918749b-1332-49a7-8a0e-94a91ea148f4
2025-09-08 03:17:54,941 - DEBUG - =>VN ctest-mgmt_vn-99787422 has no policy to be verified
2025-09-08 03:17:54,941 - DEBUG - Verifying the vn in opserver
2025-09-08 03:17:54,941 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422 virtual network link through opserver 10.0.0.41
2025-09-08 03:17:54,941 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:17:54,956 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422?flat'}
2025-09-08 03:17:54,957 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422 virtual network link through opserver 10.0.0.27
2025-09-08 03:17:54,957 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:17:54,972 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422?flat'}
2025-09-08 03:17:54,972 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422 virtual network link through opserver 10.0.0.38
2025-09-08 03:17:54,972 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:17:54,989 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422?flat'}
2025-09-08 03:17:54,989 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87977112:ctest-mgmt_vn-99787422 is found in opserver
2025-09-08 03:17:55,036 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:17:55,053 - DEBUG - VRF ids for VN ctest-mgmt_vn-99787422: {}
2025-09-08 03:17:55,328 - DEBUG - Response for create_network : {'network': {'id': '0f59edc8-5543-41d9-8812-97e8e33307dd', 'name': 'ctest-left_vn-48307109', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'project_id': '1bb8aed0338a42dc88773ae245eab56a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87977112', 'ctest-left_vn-48307109'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:17:55,559 - DEBUG - Response for create_subnet : {'subnet': {'id': '06199ea5-270d-4234-8875-328c3768dc06', 'name': '', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'network_id': '0f59edc8-5543-41d9-8812-97e8e33307dd', 'ip_version': 4, 'cidr': '190.243.210.0/26', 'allocation_pools': [{'start': '190.243.210.2', 'end': '190.243.210.62'}], 'gateway_ip': '190.243.210.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '190.243.210.2', 'tags': [], 'project_id': '1bb8aed0338a42dc88773ae245eab56a'}}
2025-09-08 03:17:55,581 - INFO - Created VN ctest-left_vn-48307109
2025-09-08 03:17:55,593 - DEBUG - VN ctest-left_vn-48307109 UUID is 0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,675 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:55,681 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:55,693 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:55,781 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,794 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,803 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:17:55,812 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:17:55,819 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/120f5cd8-3c5e-4639-a5e0-675723bf5de5
2025-09-08 03:17:55,827 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:17:55,827 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,836 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:17:55,858 - INFO - Verified VN network id 15 for VN 0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,858 - INFO - Verifications in API Server for VN ctest-left_vn-48307109 passed
2025-09-08 03:17:55,858 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:55,869 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:17:55,877 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:17:55,884 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/120f5cd8-3c5e-4639-a5e0-675723bf5de5
2025-09-08 03:17:55,898 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1106176628175880665', 'uuid-lslong': '9805066365238970333'}, 'enable': 'true', 'created': '2025-09-08T03:17:55', 'last-modified': '2025-09-08T03:17:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397749'}
2025-09-08 03:17:55,904 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:17:55,910 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1106176628175880665', 'uuid-lslong': '9805066365238970333'}, 'enable': 'true', 'created': '2025-09-08T03:17:55', 'last-modified': '2025-09-08T03:17:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416232'}
2025-09-08 03:17:55,916 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:17:55,922 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1106176628175880665', 'uuid-lslong': '9805066365238970333'}, 'enable': 'true', 'created': '2025-09-08T03:17:55', 'last-modified': '2025-09-08T03:17:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426764'}
2025-09-08 03:17:55,929 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:17:55,929 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-48307109 passed
2025-09-08 03:17:55,931 - DEBUG - ====Verifying policy data for ctest-left_vn-48307109 in API_Server ======
2025-09-08 03:17:55,931 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:55,939 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:55,953 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:56,053 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/0f59edc8-5543-41d9-8812-97e8e33307dd
2025-09-08 03:17:56,067 - DEBUG - =>VN ctest-left_vn-48307109 has no policy to be verified
2025-09-08 03:17:56,067 - DEBUG - Verifying the vn in opserver
2025-09-08 03:17:56,067 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109 virtual network link through opserver 10.0.0.41
2025-09-08 03:17:56,067 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:17:56,081 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109?flat'}
2025-09-08 03:17:56,081 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109 virtual network link through opserver 10.0.0.27
2025-09-08 03:17:56,081 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:17:56,095 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109?flat'}
2025-09-08 03:17:56,095 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109 virtual network link through opserver 10.0.0.38
2025-09-08 03:17:56,095 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:17:56,109 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109?flat'}
2025-09-08 03:17:56,109 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87977112:ctest-left_vn-48307109 is found in opserver
2025-09-08 03:17:56,138 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:17:56,154 - DEBUG - VRF ids for VN ctest-left_vn-48307109: {}
2025-09-08 03:17:56,444 - DEBUG - Response for create_network : {'network': {'id': 'c435a576-54ab-4872-be28-6359728ec7bd', 'name': 'ctest-right_vn-57798932', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'project_id': '1bb8aed0338a42dc88773ae245eab56a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87977112', 'ctest-right_vn-57798932'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:17:56,703 - DEBUG - Response for create_subnet : {'subnet': {'id': '5cda7356-20bc-4c1b-9416-e2219302542b', 'name': '', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'network_id': 'c435a576-54ab-4872-be28-6359728ec7bd', 'ip_version': 4, 'cidr': '213.226.157.0/26', 'allocation_pools': [{'start': '213.226.157.2', 'end': '213.226.157.62'}], 'gateway_ip': '213.226.157.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '213.226.157.2', 'tags': [], 'project_id': '1bb8aed0338a42dc88773ae245eab56a'}}
2025-09-08 03:17:56,724 - INFO - Created VN ctest-right_vn-57798932
2025-09-08 03:17:56,740 - DEBUG - VN ctest-right_vn-57798932 UUID is c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:56,821 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:56,829 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:56,840 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:56,938 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:56,950 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:56,961 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:17:56,970 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:17:56,978 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f914f031-12da-48e2-8ddc-d18a9d410e94
2025-09-08 03:17:56,986 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:17:56,986 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:56,996 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:17:57,013 - INFO - Verified VN network id 16 for VN c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:57,013 - INFO - Verifications in API Server for VN ctest-right_vn-57798932 passed
2025-09-08 03:17:57,014 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:57,025 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:17:57,032 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:17:57,040 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f914f031-12da-48e2-8ddc-d18a9d410e94
2025-09-08 03:17:57,053 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14138388532844775538', 'uuid-lslong': '13702311102099933117'}, 'enable': 'true', 'created': '2025-09-08T03:17:56', 'last-modified': '2025-09-08T03:17:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410225'}
2025-09-08 03:17:57,063 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:17:57,069 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14138388532844775538', 'uuid-lslong': '13702311102099933117'}, 'enable': 'true', 'created': '2025-09-08T03:17:56', 'last-modified': '2025-09-08T03:17:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.431949'}
2025-09-08 03:17:57,075 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:17:57,081 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14138388532844775538', 'uuid-lslong': '13702311102099933117'}, 'enable': 'true', 'created': '2025-09-08T03:17:56', 'last-modified': '2025-09-08T03:17:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435795'}
2025-09-08 03:17:57,088 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:17:57,088 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-57798932 passed
2025-09-08 03:17:57,088 - DEBUG - ====Verifying policy data for ctest-right_vn-57798932 in API_Server ======
2025-09-08 03:17:57,088 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:57,095 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:57,107 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:57,200 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/c435a576-54ab-4872-be28-6359728ec7bd
2025-09-08 03:17:57,213 - DEBUG - =>VN ctest-right_vn-57798932 has no policy to be verified
2025-09-08 03:17:57,213 - DEBUG - Verifying the vn in opserver
2025-09-08 03:17:57,213 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932 virtual network link through opserver 10.0.0.41
2025-09-08 03:17:57,213 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:17:57,237 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932?flat'}
2025-09-08 03:17:57,237 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932 virtual network link through opserver 10.0.0.27
2025-09-08 03:17:57,237 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:17:57,249 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932?flat'}
2025-09-08 03:17:57,249 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932 virtual network link through opserver 10.0.0.38
2025-09-08 03:17:57,249 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:17:57,263 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932?flat'}
2025-09-08 03:17:57,263 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87977112:ctest-right_vn-57798932 is found in opserver
2025-09-08 03:17:57,305 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:17:57,331 - DEBUG - VRF ids for VN ctest-right_vn-57798932: {}
2025-09-08 03:17:57,621 - DEBUG - Response for create_network : {'network': {'id': 'a4d2d044-d0a7-468d-87cf-5ba4543fc89f', 'name': 'ctest-trans_left_vn-64143375', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'project_id': '1bb8aed0338a42dc88773ae245eab56a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87977112', 'ctest-trans_left_vn-64143375'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:17:57,865 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aefaf18a-874f-4018-9c8f-12a183f6e6c1', 'name': '', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'network_id': 'a4d2d044-d0a7-468d-87cf-5ba4543fc89f', 'ip_version': 4, 'cidr': '138.79.90.0/26', 'allocation_pools': [{'start': '138.79.90.2', 'end': '138.79.90.62'}], 'gateway_ip': '138.79.90.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.79.90.2', 'tags': [], 'project_id': '1bb8aed0338a42dc88773ae245eab56a'}}
2025-09-08 03:17:57,894 - INFO - Created VN ctest-trans_left_vn-64143375
2025-09-08 03:17:57,906 - DEBUG - VN ctest-trans_left_vn-64143375 UUID is a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:57,979 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:57,985 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:57,996 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:58,103 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,117 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,127 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:17:58,138 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:17:58,147 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/9a1c7f9c-9e7f-423d-a7db-2b14210b4d06
2025-09-08 03:17:58,153 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-08 03:17:58,153 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,162 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:17:58,181 - INFO - Verified VN network id 17 for VN a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,181 - INFO - Verifications in API Server for VN ctest-trans_left_vn-64143375 passed
2025-09-08 03:17:58,181 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,196 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:17:58,205 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:17:58,212 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/9a1c7f9c-9e7f-423d-a7db-2b14210b4d06
2025-09-08 03:17:58,223 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11876784161306396301', 'uuid-lslong': '9786141276645607583'}, 'enable': 'true', 'created': '2025-09-08T03:17:57', 'last-modified': '2025-09-08T03:17:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399879'}
2025-09-08 03:17:58,230 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-08 03:17:58,236 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11876784161306396301', 'uuid-lslong': '9786141276645607583'}, 'enable': 'true', 'created': '2025-09-08T03:17:57', 'last-modified': '2025-09-08T03:17:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413036'}
2025-09-08 03:17:58,242 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-08 03:17:58,247 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11876784161306396301', 'uuid-lslong': '9786141276645607583'}, 'enable': 'true', 'created': '2025-09-08T03:17:57', 'last-modified': '2025-09-08T03:17:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428492'}
2025-09-08 03:17:58,253 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-08 03:17:58,253 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-64143375 passed
2025-09-08 03:17:58,253 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-64143375 in API_Server ======
2025-09-08 03:17:58,254 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:58,260 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:58,270 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:58,364 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a4d2d044-d0a7-468d-87cf-5ba4543fc89f
2025-09-08 03:17:58,374 - DEBUG - =>VN ctest-trans_left_vn-64143375 has no policy to be verified
2025-09-08 03:17:58,375 - DEBUG - Verifying the vn in opserver
2025-09-08 03:17:58,375 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375 virtual network link through opserver 10.0.0.41
2025-09-08 03:17:58,375 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:17:58,386 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375?flat'}
2025-09-08 03:17:58,386 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375 virtual network link through opserver 10.0.0.27
2025-09-08 03:17:58,386 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:17:58,400 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375?flat'}
2025-09-08 03:17:58,400 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375 virtual network link through opserver 10.0.0.38
2025-09-08 03:17:58,400 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:17:58,412 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375?flat'}
2025-09-08 03:17:58,412 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87977112:ctest-trans_left_vn-64143375 is found in opserver
2025-09-08 03:17:58,456 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:17:58,477 - DEBUG - VRF ids for VN ctest-trans_left_vn-64143375: {}
2025-09-08 03:17:58,783 - DEBUG - Response for create_network : {'network': {'id': '278a3deb-56ad-4df3-9d1b-5c055ad179b1', 'name': 'ctest-trans_right_vn-38061267', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'project_id': '1bb8aed0338a42dc88773ae245eab56a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87977112', 'ctest-trans_right_vn-38061267'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:17:59,022 - DEBUG - Response for create_subnet : {'subnet': {'id': '94305524-7cac-47a0-84fa-82341b9f0fea', 'name': '', 'tenant_id': '1bb8aed0338a42dc88773ae245eab56a', 'network_id': '278a3deb-56ad-4df3-9d1b-5c055ad179b1', 'ip_version': 4, 'cidr': '103.31.175.128/26', 'allocation_pools': [{'start': '103.31.175.130', 'end': '103.31.175.190'}], 'gateway_ip': '103.31.175.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '103.31.175.130', 'tags': [], 'project_id': '1bb8aed0338a42dc88773ae245eab56a'}}
2025-09-08 03:17:59,057 - INFO - Created VN ctest-trans_right_vn-38061267
2025-09-08 03:17:59,072 - DEBUG - VN ctest-trans_right_vn-38061267 UUID is 278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,152 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:59,158 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:59,171 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:59,273 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,289 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,302 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:17:59,316 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:17:59,325 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3dd878cf-24ca-409c-861e-384989a69ec6
2025-09-08 03:17:59,336 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-08 03:17:59,336 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,348 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:17:59,366 - INFO - Verified VN network id 18 for VN 278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,367 - INFO - Verifications in API Server for VN ctest-trans_right_vn-38061267 passed
2025-09-08 03:17:59,367 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,377 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:17:59,387 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:17:59,397 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3dd878cf-24ca-409c-861e-384989a69ec6
2025-09-08 03:17:59,411 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2849157795246067187', 'uuid-lslong': '11320743266394143153'}, 'enable': 'true', 'created': '2025-09-08T03:17:58', 'last-modified': '2025-09-08T03:17:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449317'}
2025-09-08 03:17:59,422 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-08 03:17:59,428 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2849157795246067187', 'uuid-lslong': '11320743266394143153'}, 'enable': 'true', 'created': '2025-09-08T03:17:58', 'last-modified': '2025-09-08T03:17:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462320'}
2025-09-08 03:17:59,438 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-08 03:17:59,445 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87977112', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2849157795246067187', 'uuid-lslong': '11320743266394143153'}, 'enable': 'true', 'created': '2025-09-08T03:17:58', 'last-modified': '2025-09-08T03:17:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482874'}
2025-09-08 03:17:59,455 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-08 03:17:59,455 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-38061267 passed
2025-09-08 03:17:59,455 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-38061267 in API_Server ======
2025-09-08 03:17:59,455 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:17:59,462 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:17:59,474 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:17:59,578 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/278a3deb-56ad-4df3-9d1b-5c055ad179b1
2025-09-08 03:17:59,596 - DEBUG - =>VN ctest-trans_right_vn-38061267 has no policy to be verified
2025-09-08 03:17:59,596 - DEBUG - Verifying the vn in opserver
2025-09-08 03:17:59,596 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267 virtual network link through opserver 10.0.0.41
2025-09-08 03:17:59,597 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:17:59,611 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267?flat'}
2025-09-08 03:17:59,611 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267 virtual network link through opserver 10.0.0.27
2025-09-08 03:17:59,611 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:17:59,625 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267?flat'}
2025-09-08 03:17:59,625 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267 virtual network link through opserver 10.0.0.38
2025-09-08 03:17:59,625 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:17:59,641 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267?flat'}
2025-09-08 03:17:59,641 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87977112:ctest-trans_right_vn-38061267 is found in opserver
2025-09-08 03:17:59,677 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:17:59,699 - DEBUG - VRF ids for VN ctest-trans_right_vn-38061267: {}
2025-09-08 03:18:00,867 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-1)
2025-09-08 03:18:02,454 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-2)
2025-09-08 03:18:02,454 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-10889435']
2025-09-08 03:18:02,762 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-10889435'] created succesfully
2025-09-08 03:18:03,138 - DEBUG - Image by name tiny_trans_fw not found
2025-09-08 03:18:03,138 - DEBUG - Installing image tiny_trans_fw
2025-09-08 03:18:03,138 - 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-09-08 03:18:03,139 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-08 03:18:03,139 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-08 03:18:03,157 - 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-09-08 03:18:03,201 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-08 03:18:03,202 - DEBUG - Unzip image
2025-09-08 03:18:03,202 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-08 03:18:03,228 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-09-08 03:18:03,229 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-10889435']
2025-09-08 03:18:03,343 - INFO - Service template: ['default-domain', 'ctest-service_template_1-10889435'] deleted successfully.
2025-09-08 03:18:03,343 - INFO - Skip interface_detach for VM ctest-right_vm-97845801 in state BUILD
2025-09-08 03:18:03,343 - INFO - Deleting VM ctest-right_vm-97845801
2025-09-08 03:18:03,343 - ERROR - VM ctest-right_vm-97845801 has failed to come up
2025-09-08 03:18:03,343 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:03,343 - ERROR - VM ctest-right_vm-97845801 has failed to come up
2025-09-08 03:18:03,344 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:03,537 - INFO - Skip interface_detach for VM ctest-left_vm-81882990 in state BUILD
2025-09-08 03:18:03,538 - INFO - Deleting VM ctest-left_vm-81882990
2025-09-08 03:18:03,538 - ERROR - VM ctest-left_vm-81882990 has failed to come up
2025-09-08 03:18:03,538 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:03,538 - ERROR - VM ctest-left_vm-81882990 has failed to come up
2025-09-08 03:18:03,538 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:03,685 - INFO - Deleting VN ctest-trans_right_vn-38061267
2025-09-08 03:18:03,873 - DEBUG - Response for deleting network ()
2025-09-08 03:18:03,873 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2bba3797-92a2-4c53-94c7-67bd69451944
2025-09-08 03:18:03,880 - DEBUG - Response Code: 404
2025-09-08 03:18:03,880 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:03,886 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:03,914 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:04,012 - INFO - Validated that VN ctest-trans_right_vn-38061267 is not found in API Server
2025-09-08 03:18:04,023 - DEBUG - VN ctest-trans_right_vn-38061267 is not present in Agent 10.0.0.65
2025-09-08 03:18:04,074 - DEBUG - VN ctest-trans_right_vn-38061267 is not present in Agent 10.0.0.23
2025-09-08 03:18:04,074 - INFO - Validated that VN ctest-trans_right_vn-38061267 is not in any agent
2025-09-08 03:18:04,114 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-38061267 info
2025-09-08 03:18:04,114 - INFO - Deleting VN ctest-trans_left_vn-64143375
2025-09-08 03:18:04,332 - DEBUG - Response for deleting network ()
2025-09-08 03:18:04,332 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/2c63ad4a-51b1-4a89-b542-e66f6b6a8562
2025-09-08 03:18:04,356 - DEBUG - Response Code: 404
2025-09-08 03:18:04,356 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:04,370 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:04,387 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:04,510 - INFO - Validated that VN ctest-trans_left_vn-64143375 is not found in API Server
2025-09-08 03:18:04,535 - DEBUG - VN ctest-trans_left_vn-64143375 is not present in Agent 10.0.0.65
2025-09-08 03:18:04,549 - DEBUG - VN ctest-trans_left_vn-64143375 is not present in Agent 10.0.0.23
2025-09-08 03:18:04,549 - INFO - Validated that VN ctest-trans_left_vn-64143375 is not in any agent
2025-09-08 03:18:04,602 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-64143375 info
2025-09-08 03:18:04,603 - INFO - Deleting VN ctest-right_vn-57798932
2025-09-08 03:18:04,669 - DEBUG - VN c435a576-54ab-4872-be28-6359728ec7bd still in use: Unable to complete operation on network c435a576-54ab-4872-be28-6359728ec7bd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-bc7525b5-3eef-4911-adbd-9c3f1400f861']
2025-09-08 03:18:04,669 - WARNING - Deleting VN ctest-right_vn-57798932 failed..Will retry
2025-09-08 03:18:06,819 - DEBUG - Response for deleting network ()
2025-09-08 03:18:06,819 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/d21ef6fe-3b81-42f7-bae9-54870c40e096
2025-09-08 03:18:06,827 - DEBUG - Response Code: 404
2025-09-08 03:18:06,827 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:06,834 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:06,856 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:06,959 - INFO - Validated that VN ctest-right_vn-57798932 is not found in API Server
2025-09-08 03:18:06,981 - DEBUG - VN ctest-right_vn-57798932 is not present in Agent 10.0.0.65
2025-09-08 03:18:07,002 - DEBUG - VN ctest-right_vn-57798932 is not present in Agent 10.0.0.23
2025-09-08 03:18:07,002 - INFO - Validated that VN ctest-right_vn-57798932 is not in any agent
2025-09-08 03:18:07,051 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-57798932 info
2025-09-08 03:18:07,052 - INFO - Deleting VN ctest-left_vn-48307109
2025-09-08 03:18:07,221 - DEBUG - Response for deleting network ()
2025-09-08 03:18:07,221 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b5ec3c3d-3d88-4056-9e65-076fd0258f90
2025-09-08 03:18:07,228 - DEBUG - Response Code: 404
2025-09-08 03:18:07,228 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:07,242 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:07,263 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:07,374 - INFO - Validated that VN ctest-left_vn-48307109 is not found in API Server
2025-09-08 03:18:07,397 - DEBUG - VN ctest-left_vn-48307109 is not present in Agent 10.0.0.65
2025-09-08 03:18:07,418 - DEBUG - VN ctest-left_vn-48307109 is not present in Agent 10.0.0.23
2025-09-08 03:18:07,418 - INFO - Validated that VN ctest-left_vn-48307109 is not in any agent
2025-09-08 03:18:07,459 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-48307109 info
2025-09-08 03:18:07,459 - INFO - Deleting VN ctest-mgmt_vn-99787422
2025-09-08 03:18:07,627 - DEBUG - Response for deleting network ()
2025-09-08 03:18:07,627 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/6d9f704d-6918-41f6-9f35-745c29af37fe
2025-09-08 03:18:07,634 - DEBUG - Response Code: 404
2025-09-08 03:18:07,634 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:07,641 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:07,666 - DEBUG - Requesting: http://10.0.0.41:8082/project/1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:07,774 - INFO - Validated that VN ctest-mgmt_vn-99787422 is not found in API Server
2025-09-08 03:18:07,793 - DEBUG - VN ctest-mgmt_vn-99787422 is not present in Agent 10.0.0.65
2025-09-08 03:18:07,809 - DEBUG - VN ctest-mgmt_vn-99787422 is not present in Agent 10.0.0.23
2025-09-08 03:18:07,809 - INFO - Validated that VN ctest-mgmt_vn-99787422 is not in any agent
2025-09-08 03:18:07,850 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99787422 info
2025-09-08 03:18:09,258 - DEBUG - No XMPP flaps were noticed during the test
2025-09-08 03:18:09,258 - INFO -
2025-09-08 03:18:09,258 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:17]
2025-09-08 03:18:09,258 - INFO - --------------------------------------------------------------------------------
2025-09-08 03:18:10,070 - INFO - Deleted project: ctest-TestSvcRegr-87977112, ID : 1bb8aed0-338a-42dc-8877-3ae245eab56a
2025-09-08 03:18:10,269 - INFO - Domain Default found not creating
2025-09-08 03:18:10,455 - INFO - Project ctest-TestSvcRegr-76553838 not found, creating it
2025-09-08 03:18:10,987 - INFO - Created Project:ctest-TestSvcRegr-76553838, ID : 253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:12,669 - INFO - ================================================================================
2025-09-08 03:18:12,669 - INFO - STARTING TEST : test_svc_CEM_22032
2025-09-08 03:18:14,057 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.129': '0'}} with
2025-09-08 03:18:14,057 - INFO - Initial checks done. Running the testcase now
2025-09-08 03:18:14,057 - INFO -
2025-09-08 03:18:14,819 - DEBUG - Response for create_network : {'network': {'id': 'f51cdc94-5226-47c7-8318-931d5ea9ca6e', 'name': 'ctest-mgmt_vn-60300976', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'ctest-mgmt_vn-60300976'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:15,129 - DEBUG - Response for create_subnet : {'subnet': {'id': '81f2ffaa-0429-4aff-8872-d2b79515c0d9', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': 'f51cdc94-5226-47c7-8318-931d5ea9ca6e', 'ip_version': 4, 'cidr': '118.251.19.0/26', 'allocation_pools': [{'start': '118.251.19.2', 'end': '118.251.19.62'}], 'gateway_ip': '118.251.19.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '118.251.19.2', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:15,152 - INFO - Created VN ctest-mgmt_vn-60300976
2025-09-08 03:18:15,169 - DEBUG - VN ctest-mgmt_vn-60300976 UUID is f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,251 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:15,599 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:15,631 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:15,647 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:15,743 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,763 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,775 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:15,782 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:15,788 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/87af15ee-b86e-4952-9bb3-25104f844846
2025-09-08 03:18:15,795 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:15,795 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,804 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:15,819 - INFO - Verified VN network id 14 for VN f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,819 - INFO - Verifications in API Server for VN ctest-mgmt_vn-60300976 passed
2025-09-08 03:18:15,819 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:15,831 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:15,840 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:15,848 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/87af15ee-b86e-4952-9bb3-25104f844846
2025-09-08 03:18:15,859 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17662234368231753671', 'uuid-lslong': '9446461972761135726'}, 'enable': 'true', 'created': '2025-09-08T03:18:14', 'last-modified': '2025-09-08T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.803345'}
2025-09-08 03:18:15,868 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:15,874 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17662234368231753671', 'uuid-lslong': '9446461972761135726'}, 'enable': 'true', 'created': '2025-09-08T03:18:14', 'last-modified': '2025-09-08T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.814847'}
2025-09-08 03:18:15,879 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:15,885 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17662234368231753671', 'uuid-lslong': '9446461972761135726'}, 'enable': 'true', 'created': '2025-09-08T03:18:14', 'last-modified': '2025-09-08T03:18:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.822561'}
2025-09-08 03:18:15,891 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:15,891 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-60300976 passed
2025-09-08 03:18:15,891 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-60300976 in API_Server ======
2025-09-08 03:18:15,891 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:15,898 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:15,910 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:16,024 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f51cdc94-5226-47c7-8318-931d5ea9ca6e
2025-09-08 03:18:16,034 - DEBUG - =>VN ctest-mgmt_vn-60300976 has no policy to be verified
2025-09-08 03:18:16,034 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:16,034 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:16,034 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:16,371 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:16,445 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976?flat'}
2025-09-08 03:18:16,445 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:16,445 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:16,798 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:16,859 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976?flat'}
2025-09-08 03:18:16,859 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:16,859 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:17,227 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:17,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976?flat'}
2025-09-08 03:18:17,308 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-60300976 is found in opserver
2025-09-08 03:18:17,350 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:17,372 - DEBUG - VRF ids for VN ctest-mgmt_vn-60300976: {}
2025-09-08 03:18:17,675 - DEBUG - Response for create_network : {'network': {'id': 'd26d6741-78b4-426e-a727-67f0909bbcf8', 'name': 'service-left-vn-52551290', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'service-left-vn-52551290'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:17,989 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c5d2eb94-e95d-40bc-b02d-2249e144ade5', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': 'd26d6741-78b4-426e-a727-67f0909bbcf8', 'ip_version': 4, 'cidr': '27.69.11.0/26', 'allocation_pools': [{'start': '27.69.11.2', 'end': '27.69.11.62'}], 'gateway_ip': '27.69.11.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.69.11.2', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:18,019 - INFO - Created VN service-left-vn-52551290
2025-09-08 03:18:18,030 - DEBUG - VN service-left-vn-52551290 UUID is d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,127 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:18,134 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:18,145 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:18,247 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,259 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,272 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:18,280 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:18,287 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b724ec73-5e2e-478e-9bc8-34bdd7a07f18
2025-09-08 03:18:18,293 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:18,293 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,304 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:18,324 - INFO - Verified VN network id 15 for VN d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,325 - INFO - Verifications in API Server for VN service-left-vn-52551290 passed
2025-09-08 03:18:18,325 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,341 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:18,353 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:18,361 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b724ec73-5e2e-478e-9bc8-34bdd7a07f18
2025-09-08 03:18:18,379 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15162889051321942638', 'uuid-lslong': '12044710011341618424'}, 'enable': 'true', 'created': '2025-09-08T03:18:17', 'last-modified': '2025-09-08T03:18:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.459498'}
2025-09-08 03:18:18,384 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:18,391 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15162889051321942638', 'uuid-lslong': '12044710011341618424'}, 'enable': 'true', 'created': '2025-09-08T03:18:17', 'last-modified': '2025-09-08T03:18:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.462358'}
2025-09-08 03:18:18,397 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:18,403 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15162889051321942638', 'uuid-lslong': '12044710011341618424'}, 'enable': 'true', 'created': '2025-09-08T03:18:17', 'last-modified': '2025-09-08T03:18:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487357'}
2025-09-08 03:18:18,409 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:18,409 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-52551290 passed
2025-09-08 03:18:18,409 - DEBUG - ====Verifying policy data for service-left-vn-52551290 in API_Server ======
2025-09-08 03:18:18,409 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:18,415 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:18,426 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:18,521 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/d26d6741-78b4-426e-a727-67f0909bbcf8
2025-09-08 03:18:18,538 - DEBUG - =>VN service-left-vn-52551290 has no policy to be verified
2025-09-08 03:18:18,539 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:18,539 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:18,539 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:18,596 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290?flat'}
2025-09-08 03:18:18,596 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:18,596 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:18,651 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290?flat'}
2025-09-08 03:18:18,651 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:18,651 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:18,721 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290?flat'}
2025-09-08 03:18:18,721 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:service-left-vn-52551290 is found in opserver
2025-09-08 03:18:18,755 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:18,771 - DEBUG - VRF ids for VN service-left-vn-52551290: {}
2025-09-08 03:18:19,101 - DEBUG - Response for create_network : {'network': {'id': '204331ca-f1b0-4526-ae2e-7c82be5591ad', 'name': 'service-right-vn-29096753', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'service-right-vn-29096753'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:19,345 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c8ac9ab4-bb4f-465c-91c8-0d18054f5d3e', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': '204331ca-f1b0-4526-ae2e-7c82be5591ad', 'ip_version': 4, 'cidr': '14.45.11.128/26', 'allocation_pools': [{'start': '14.45.11.130', 'end': '14.45.11.190'}], 'gateway_ip': '14.45.11.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.45.11.130', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:19,375 - INFO - Created VN service-right-vn-29096753
2025-09-08 03:18:19,389 - DEBUG - VN service-right-vn-29096753 UUID is 204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,477 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:19,484 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:19,496 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:19,584 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,600 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,612 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:19,627 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:19,637 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f8a759f2-32aa-414f-9a62-aa64da36e242
2025-09-08 03:18:19,650 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:19,650 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,663 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:19,683 - INFO - Verified VN network id 16 for VN 204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,683 - INFO - Verifications in API Server for VN service-right-vn-29096753 passed
2025-09-08 03:18:19,683 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,695 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:19,705 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:19,716 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f8a759f2-32aa-414f-9a62-aa64da36e242
2025-09-08 03:18:19,735 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2324756580361323814', 'uuid-lslong': '12551106112509022637'}, 'enable': 'true', 'created': '2025-09-08T03:18:19', 'last-modified': '2025-09-08T03:18:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.478181'}
2025-09-08 03:18:19,745 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:19,752 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2324756580361323814', 'uuid-lslong': '12551106112509022637'}, 'enable': 'true', 'created': '2025-09-08T03:18:19', 'last-modified': '2025-09-08T03:18:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493355'}
2025-09-08 03:18:19,762 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:19,768 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2324756580361323814', 'uuid-lslong': '12551106112509022637'}, 'enable': 'true', 'created': '2025-09-08T03:18:19', 'last-modified': '2025-09-08T03:18:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.513588'}
2025-09-08 03:18:19,775 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:19,776 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-29096753 passed
2025-09-08 03:18:19,776 - DEBUG - ====Verifying policy data for service-right-vn-29096753 in API_Server ======
2025-09-08 03:18:19,776 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:19,783 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:19,794 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:19,901 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/204331ca-f1b0-4526-ae2e-7c82be5591ad
2025-09-08 03:18:19,912 - DEBUG - =>VN service-right-vn-29096753 has no policy to be verified
2025-09-08 03:18:19,912 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:19,912 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:19,912 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:19,989 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753?flat'}
2025-09-08 03:18:19,989 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:19,989 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:20,060 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753?flat'}
2025-09-08 03:18:20,061 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:20,061 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:20,125 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753?flat'}
2025-09-08 03:18:20,125 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:service-right-vn-29096753 is found in opserver
2025-09-08 03:18:20,167 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:20,187 - DEBUG - VRF ids for VN service-right-vn-29096753: {}
2025-09-08 03:18:20,252 - DEBUG - Services list from nova: [, , , ]
2025-09-08 03:18:21,403 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-1)
2025-09-08 03:18:22,975 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-2)
2025-09-08 03:18:22,976 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-36651153']
2025-09-08 03:18:23,278 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-36651153'] created succesfully
2025-09-08 03:18:23,702 - DEBUG - Image by name tiny_nat_fw not found
2025-09-08 03:18:23,702 - DEBUG - Installing image tiny_nat_fw
2025-09-08 03:18:23,702 - 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-09-08 03:18:23,703 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:23,703 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:23,703 - DEBUG - Unzip image
2025-09-08 03:18:23,703 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:23,723 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-08 03:18:23,723 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-36651153']
2025-09-08 03:18:23,839 - INFO - Service template: ['default-domain', 'ctest-service_template_1-36651153'] deleted successfully.
2025-09-08 03:18:23,839 - INFO - Skip interface_detach for VM ctest-right_vm-10889019 in state BUILD
2025-09-08 03:18:23,839 - INFO - Deleting VM ctest-right_vm-10889019
2025-09-08 03:18:23,887 - ERROR - VM ctest-right_vm-10889019 has failed to come up
2025-09-08 03:18:23,888 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:23,888 - ERROR - VM ctest-right_vm-10889019 has failed to come up
2025-09-08 03:18:23,888 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:24,005 - INFO - Skip interface_detach for VM ctest-left_vm-36121357 in state BUILD
2025-09-08 03:18:24,006 - INFO - Deleting VM ctest-left_vm-36121357
2025-09-08 03:18:24,006 - ERROR - VM ctest-left_vm-36121357 has failed to come up
2025-09-08 03:18:24,007 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:24,007 - ERROR - VM ctest-left_vm-36121357 has failed to come up
2025-09-08 03:18:24,007 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:24,157 - INFO - Deleting VN service-right-vn-29096753
2025-09-08 03:18:24,227 - DEBUG - VN 204331ca-f1b0-4526-ae2e-7c82be5591ad still in use: Unable to complete operation on network 204331ca-f1b0-4526-ae2e-7c82be5591ad. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-40223220-fe62-4298-ad4a-b0ec111f03fb']
2025-09-08 03:18:24,228 - WARNING - Deleting VN service-right-vn-29096753 failed..Will retry
2025-09-08 03:18:26,401 - DEBUG - Response for deleting network ()
2025-09-08 03:18:26,401 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/83cd0442-41f7-4eac-ba27-3709435b5066
2025-09-08 03:18:26,408 - DEBUG - Response Code: 404
2025-09-08 03:18:26,408 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:26,414 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:26,475 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:26,580 - INFO - Validated that VN service-right-vn-29096753 is not found in API Server
2025-09-08 03:18:26,604 - DEBUG - VN service-right-vn-29096753 is not present in Agent 10.0.0.65
2025-09-08 03:18:26,650 - DEBUG - VN service-right-vn-29096753 is not present in Agent 10.0.0.23
2025-09-08 03:18:26,650 - INFO - Validated that VN service-right-vn-29096753 is not in any agent
2025-09-08 03:18:26,701 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-29096753 info
2025-09-08 03:18:26,701 - INFO - Deleting VN service-left-vn-52551290
2025-09-08 03:18:26,918 - DEBUG - Response for deleting network ()
2025-09-08 03:18:26,919 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/daf20676-0dff-4846-98f5-9f54716e2903
2025-09-08 03:18:26,924 - DEBUG - Response Code: 404
2025-09-08 03:18:26,925 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:26,931 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:26,985 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:27,073 - INFO - Validated that VN service-left-vn-52551290 is not found in API Server
2025-09-08 03:18:27,095 - DEBUG - VN service-left-vn-52551290 is not present in Agent 10.0.0.65
2025-09-08 03:18:27,116 - DEBUG - VN service-left-vn-52551290 is not present in Agent 10.0.0.23
2025-09-08 03:18:27,116 - INFO - Validated that VN service-left-vn-52551290 is not in any agent
2025-09-08 03:18:27,158 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-52551290 info
2025-09-08 03:18:27,158 - INFO - Deleting VN ctest-mgmt_vn-60300976
2025-09-08 03:18:27,322 - DEBUG - Response for deleting network ()
2025-09-08 03:18:27,322 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/0b1b184e-dff4-4689-aa3d-746ab80e43cf
2025-09-08 03:18:27,333 - DEBUG - Response Code: 404
2025-09-08 03:18:27,333 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:27,350 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:27,367 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:27,468 - INFO - Validated that VN ctest-mgmt_vn-60300976 is not found in API Server
2025-09-08 03:18:27,491 - DEBUG - VN ctest-mgmt_vn-60300976 is not present in Agent 10.0.0.65
2025-09-08 03:18:27,511 - DEBUG - VN ctest-mgmt_vn-60300976 is not present in Agent 10.0.0.23
2025-09-08 03:18:27,511 - INFO - Validated that VN ctest-mgmt_vn-60300976 is not in any agent
2025-09-08 03:18:27,558 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-60300976 info
2025-09-08 03:18:28,989 - DEBUG - No XMPP flaps were noticed during the test
2025-09-08 03:18:28,989 - INFO -
2025-09-08 03:18:28,989 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-09-08 03:18:28,989 - INFO - --------------------------------------------------------------------------------
2025-09-08 03:18:28,992 - INFO - ================================================================================
2025-09-08 03:18:28,992 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-09-08 03:18:30,364 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.14': '0', '10.20.0.129': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.129': '0'}} with
2025-09-08 03:18:30,364 - INFO - Initial checks done. Running the testcase now
2025-09-08 03:18:30,364 - INFO -
2025-09-08 03:18:30,640 - DEBUG - Response for create_network : {'network': {'id': '8f2f53bc-34d7-4df2-93b4-2acf81e34bbb', 'name': 'ctest-mgmt_vn-09342040', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'ctest-mgmt_vn-09342040'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:30,847 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a7687484-8a08-4d9e-82ec-2773642ed45b', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': '8f2f53bc-34d7-4df2-93b4-2acf81e34bbb', 'ip_version': 4, 'cidr': '22.199.1.64/26', 'allocation_pools': [{'start': '22.199.1.66', 'end': '22.199.1.126'}], 'gateway_ip': '22.199.1.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22.199.1.66', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:30,873 - INFO - Created VN ctest-mgmt_vn-09342040
2025-09-08 03:18:30,885 - DEBUG - VN ctest-mgmt_vn-09342040 UUID is 8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:30,975 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:30,981 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:30,994 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:31,093 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,113 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,125 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:31,136 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:31,143 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f661a1a5-f5a4-466c-9e61-74c31f0558c1
2025-09-08 03:18:31,149 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:31,150 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,159 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:31,175 - INFO - Verified VN network id 14 for VN 8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,175 - INFO - Verifications in API Server for VN ctest-mgmt_vn-09342040 passed
2025-09-08 03:18:31,175 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,185 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:31,194 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:31,203 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/f661a1a5-f5a4-466c-9e61-74c31f0558c1
2025-09-08 03:18:31,217 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10317557339134578162', 'uuid-lslong': '10643178890109078459'}, 'enable': 'true', 'created': '2025-09-08T03:18:30', 'last-modified': '2025-09-08T03:18:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.425302'}
2025-09-08 03:18:31,224 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:31,232 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10317557339134578162', 'uuid-lslong': '10643178890109078459'}, 'enable': 'true', 'created': '2025-09-08T03:18:30', 'last-modified': '2025-09-08T03:18:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441370'}
2025-09-08 03:18:31,240 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:31,245 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10317557339134578162', 'uuid-lslong': '10643178890109078459'}, 'enable': 'true', 'created': '2025-09-08T03:18:30', 'last-modified': '2025-09-08T03:18:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454484'}
2025-09-08 03:18:31,252 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-08 03:18:31,252 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-09342040 passed
2025-09-08 03:18:31,252 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-09342040 in API_Server ======
2025-09-08 03:18:31,252 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:31,264 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:31,275 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:31,443 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/8f2f53bc-34d7-4df2-93b4-2acf81e34bbb
2025-09-08 03:18:31,454 - DEBUG - =>VN ctest-mgmt_vn-09342040 has no policy to be verified
2025-09-08 03:18:31,454 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:31,455 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:31,455 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:31,469 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040?flat'}
2025-09-08 03:18:31,469 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:31,469 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:31,483 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040?flat'}
2025-09-08 03:18:31,483 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:31,483 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:31,497 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040?flat'}
2025-09-08 03:18:31,497 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:ctest-mgmt_vn-09342040 is found in opserver
2025-09-08 03:18:31,540 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:31,557 - DEBUG - VRF ids for VN ctest-mgmt_vn-09342040: {}
2025-09-08 03:18:31,854 - DEBUG - Response for create_network : {'network': {'id': 'fe3eec38-6c0d-4020-91a2-958ed19f704b', 'name': 'ctest-left_vn-95473830', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'ctest-left_vn-95473830'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:32,101 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cfbf54b2-d75e-4f34-bd53-4492e505534d', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': 'fe3eec38-6c0d-4020-91a2-958ed19f704b', 'ip_version': 4, 'cidr': '64.246.83.128/26', 'allocation_pools': [{'start': '64.246.83.130', 'end': '64.246.83.190'}], 'gateway_ip': '64.246.83.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '64.246.83.130', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:32,133 - INFO - Created VN ctest-left_vn-95473830
2025-09-08 03:18:32,148 - DEBUG - VN ctest-left_vn-95473830 UUID is fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,222 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:32,229 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:32,240 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:32,336 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,349 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,359 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:32,367 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:32,376 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/710fce53-af8d-4bc8-9812-4f2675a2962c
2025-09-08 03:18:32,384 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:32,384 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,395 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:32,412 - INFO - Verified VN network id 15 for VN fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,412 - INFO - Verifications in API Server for VN ctest-left_vn-95473830 passed
2025-09-08 03:18:32,412 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,423 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:32,430 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:32,437 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/710fce53-af8d-4bc8-9812-4f2675a2962c
2025-09-08 03:18:32,450 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18320340061264887840', 'uuid-lslong': '10494114522361458763'}, 'enable': 'true', 'created': '2025-09-08T03:18:31', 'last-modified': '2025-09-08T03:18:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397789'}
2025-09-08 03:18:32,458 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:32,463 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18320340061264887840', 'uuid-lslong': '10494114522361458763'}, 'enable': 'true', 'created': '2025-09-08T03:18:31', 'last-modified': '2025-09-08T03:18:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406277'}
2025-09-08 03:18:32,469 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:32,476 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18320340061264887840', 'uuid-lslong': '10494114522361458763'}, 'enable': 'true', 'created': '2025-09-08T03:18:31', 'last-modified': '2025-09-08T03:18:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424204'}
2025-09-08 03:18:32,482 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-08 03:18:32,482 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-95473830 passed
2025-09-08 03:18:32,483 - DEBUG - ====Verifying policy data for ctest-left_vn-95473830 in API_Server ======
2025-09-08 03:18:32,483 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:32,489 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:32,502 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:32,594 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/fe3eec38-6c0d-4020-91a2-958ed19f704b
2025-09-08 03:18:32,605 - DEBUG - =>VN ctest-left_vn-95473830 has no policy to be verified
2025-09-08 03:18:32,605 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:32,605 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:32,605 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:32,623 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830?flat'}
2025-09-08 03:18:32,623 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:32,623 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:32,637 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830?flat'}
2025-09-08 03:18:32,637 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:32,637 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:32,651 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830?flat'}
2025-09-08 03:18:32,651 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:ctest-left_vn-95473830 is found in opserver
2025-09-08 03:18:32,696 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:32,719 - DEBUG - VRF ids for VN ctest-left_vn-95473830: {}
2025-09-08 03:18:33,005 - DEBUG - Response for create_network : {'network': {'id': '99c65c61-34c9-4691-b5cb-d29c29ae93c2', 'name': 'ctest-right_vn-50186725', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'project_id': '253a6eaec4ae4d52a2401104ae9f6612', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-76553838', 'ctest-right_vn-50186725'], 'port_security_enabled': True, 'description': ''}}
2025-09-08 03:18:33,259 - DEBUG - Response for create_subnet : {'subnet': {'id': '916c090b-e3f1-48a1-88a4-ef54defbdd31', 'name': '', 'tenant_id': '253a6eaec4ae4d52a2401104ae9f6612', 'network_id': '99c65c61-34c9-4691-b5cb-d29c29ae93c2', 'ip_version': 4, 'cidr': '132.12.107.0/26', 'allocation_pools': [{'start': '132.12.107.2', 'end': '132.12.107.62'}], 'gateway_ip': '132.12.107.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '132.12.107.2', 'tags': [], 'project_id': '253a6eaec4ae4d52a2401104ae9f6612'}}
2025-09-08 03:18:33,289 - INFO - Created VN ctest-right_vn-50186725
2025-09-08 03:18:33,302 - DEBUG - VN ctest-right_vn-50186725 UUID is 99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,382 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:33,390 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:33,402 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:33,499 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,513 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,526 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:33,536 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:33,544 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3c45fd67-7148-4090-8c43-aba72853c0ba
2025-09-08 03:18:33,550 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:33,551 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,560 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:33,575 - INFO - Verified VN network id 16 for VN 99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,575 - INFO - Verifications in API Server for VN ctest-right_vn-50186725 passed
2025-09-08 03:18:33,575 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,586 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:33,594 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:33,601 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/3c45fd67-7148-4090-8c43-aba72853c0ba
2025-09-08 03:18:33,615 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11080645505758873233', 'uuid-lslong': '13099795509293257666'}, 'enable': 'true', 'created': '2025-09-08T03:18:32', 'last-modified': '2025-09-08T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408699'}
2025-09-08 03:18:33,621 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:33,627 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11080645505758873233', 'uuid-lslong': '13099795509293257666'}, 'enable': 'true', 'created': '2025-09-08T03:18:32', 'last-modified': '2025-09-08T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421395'}
2025-09-08 03:18:33,633 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:33,639 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-76553838', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11080645505758873233', 'uuid-lslong': '13099795509293257666'}, 'enable': 'true', 'created': '2025-09-08T03:18:32', 'last-modified': '2025-09-08T03:18:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440507'}
2025-09-08 03:18:33,647 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-08 03:18:33,647 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-50186725 passed
2025-09-08 03:18:33,647 - DEBUG - ====Verifying policy data for ctest-right_vn-50186725 in API_Server ======
2025-09-08 03:18:33,647 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:33,655 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:33,669 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:33,771 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/99c65c61-34c9-4691-b5cb-d29c29ae93c2
2025-09-08 03:18:33,781 - DEBUG - =>VN ctest-right_vn-50186725 has no policy to be verified
2025-09-08 03:18:33,781 - DEBUG - Verifying the vn in opserver
2025-09-08 03:18:33,781 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725 virtual network link through opserver 10.0.0.41
2025-09-08 03:18:33,782 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-09-08 03:18:33,794 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725?flat'}
2025-09-08 03:18:33,794 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725 virtual network link through opserver 10.0.0.27
2025-09-08 03:18:33,794 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-08 03:18:33,807 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725?flat'}
2025-09-08 03:18:33,807 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725 virtual network link through opserver 10.0.0.38
2025-09-08 03:18:33,807 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-08 03:18:33,822 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725?flat'}
2025-09-08 03:18:33,822 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-76553838:ctest-right_vn-50186725 is found in opserver
2025-09-08 03:18:33,848 - DEBUG - Do not have enough data to verify VN in agent
2025-09-08 03:18:33,864 - DEBUG - VRF ids for VN ctest-right_vn-50186725: {}
2025-09-08 03:18:35,086 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-2)
2025-09-08 03:18:36,478 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4041-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4041-1)
2025-09-08 03:18:36,478 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-88506741']
2025-09-08 03:18:36,778 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-88506741'] created succesfully
2025-09-08 03:18:37,102 - DEBUG - Image by name tiny_nat_fw not found
2025-09-08 03:18:37,102 - DEBUG - Installing image tiny_nat_fw
2025-09-08 03:18:37,102 - 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-09-08 03:18:37,102 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:37,102 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:37,102 - DEBUG - Unzip image
2025-09-08 03:18:37,102 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-08 03:18:37,167 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-08 03:18:37,168 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-88506741']
2025-09-08 03:18:37,283 - INFO - Service template: ['default-domain', 'ctest-service_template_1-88506741'] deleted successfully.
2025-09-08 03:18:37,284 - INFO - Skip interface_detach for VM ctest-right_vm-59705183 in state BUILD
2025-09-08 03:18:37,284 - INFO - Deleting VM ctest-right_vm-59705183
2025-09-08 03:18:37,284 - ERROR - VM ctest-right_vm-59705183 has failed to come up
2025-09-08 03:18:37,284 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:37,284 - ERROR - VM ctest-right_vm-59705183 has failed to come up
2025-09-08 03:18:37,284 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:37,416 - INFO - Skip interface_detach for VM ctest-left_vm-05464426 in state BUILD
2025-09-08 03:18:37,416 - INFO - Deleting VM ctest-left_vm-05464426
2025-09-08 03:18:37,416 - ERROR - VM ctest-left_vm-05464426 has failed to come up
2025-09-08 03:18:37,416 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:37,416 - ERROR - VM ctest-left_vm-05464426 has failed to come up
2025-09-08 03:18:37,416 - ERROR - Nova failed to get host of the VM
2025-09-08 03:18:37,558 - INFO - Deleting VN ctest-right_vn-50186725
2025-09-08 03:18:37,621 - DEBUG - VN 99c65c61-34c9-4691-b5cb-d29c29ae93c2 still in use: Unable to complete operation on network 99c65c61-34c9-4691-b5cb-d29c29ae93c2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-50f79ba8-bf2b-487c-b5fd-89f337b63f59']
2025-09-08 03:18:37,621 - WARNING - Deleting VN ctest-right_vn-50186725 failed..Will retry
2025-09-08 03:18:39,790 - DEBUG - Response for deleting network ()
2025-09-08 03:18:39,790 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/5a1411fc-0bc2-44ed-88d4-74ae266abba5
2025-09-08 03:18:39,799 - DEBUG - Response Code: 404
2025-09-08 03:18:39,799 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:39,807 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:39,822 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:39,918 - INFO - Validated that VN ctest-right_vn-50186725 is not found in API Server
2025-09-08 03:18:39,943 - DEBUG - VN ctest-right_vn-50186725 is not present in Agent 10.0.0.65
2025-09-08 03:18:39,969 - DEBUG - VN ctest-right_vn-50186725 is not present in Agent 10.0.0.23
2025-09-08 03:18:39,969 - INFO - Validated that VN ctest-right_vn-50186725 is not in any agent
2025-09-08 03:18:40,016 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-50186725 info
2025-09-08 03:18:40,017 - INFO - Deleting VN ctest-left_vn-95473830
2025-09-08 03:18:40,192 - DEBUG - Response for deleting network ()
2025-09-08 03:18:40,192 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/bea04785-21e5-4277-bbf1-f731c4e58ed0
2025-09-08 03:18:40,198 - DEBUG - Response Code: 404
2025-09-08 03:18:40,198 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:40,205 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:40,226 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:40,324 - INFO - Validated that VN ctest-left_vn-95473830 is not found in API Server
2025-09-08 03:18:40,343 - DEBUG - VN ctest-left_vn-95473830 is not present in Agent 10.0.0.65
2025-09-08 03:18:40,363 - DEBUG - VN ctest-left_vn-95473830 is not present in Agent 10.0.0.23
2025-09-08 03:18:40,363 - INFO - Validated that VN ctest-left_vn-95473830 is not in any agent
2025-09-08 03:18:40,402 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-95473830 info
2025-09-08 03:18:40,403 - INFO - Deleting VN ctest-mgmt_vn-09342040
2025-09-08 03:18:40,552 - DEBUG - Response for deleting network ()
2025-09-08 03:18:40,552 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/8eb812af-9c68-4d83-aad1-189dbb3f844b
2025-09-08 03:18:40,569 - DEBUG - Response Code: 404
2025-09-08 03:18:40,569 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-09-08 03:18:40,576 - DEBUG - Requesting: http://10.0.0.41:8082/domain/729830a9-0e71-496c-9bfd-570f28a4effe
2025-09-08 03:18:40,589 - DEBUG - Requesting: http://10.0.0.41:8082/project/253a6eae-c4ae-4d52-a240-1104ae9f6612
2025-09-08 03:18:40,690 - INFO - Validated that VN ctest-mgmt_vn-09342040 is not found in API Server
2025-09-08 03:18:40,709 - DEBUG - VN ctest-mgmt_vn-09342040 is not present in Agent 10.0.0.65
2025-09-08 03:18:40,728 - DEBUG - VN ctest-mgmt_vn-09342040 is not present in Agent 10.0.0.23
2025-09-08 03:18:40,728 - INFO - Validated that VN ctest-mgmt_vn-09342040 is not in any agent
2025-09-08 03:18:40,766 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-09342040 info
2025-09-08 03:18:42,198 - DEBUG - No XMPP flaps were noticed during the test
2025-09-08 03:18:42,198 - INFO -
2025-09-08 03:18:42,198 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-09-08 03:18:42,198 - INFO - --------------------------------------------------------------------------------
2025-09-08 03:18:43,002 - INFO - Deleted project: ctest-TestSvcRegr-76553838, ID : 253a6eae-c4ae-4d52-a240-1104ae9f6612