]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-2)
2025-08-08 08:04:06,011 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-74583699']
2025-08-08 08:04:06,336 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-74583699'] created succesfully
2025-08-08 08:04:06,814 - DEBUG - Image by name tiny_nat_fw not found
2025-08-08 08:04:06,814 - DEBUG - Installing image tiny_nat_fw
2025-08-08 08:04:06,814 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-08 08:04:06,815 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:06,815 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:06,842 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:06,918 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:06,919 - DEBUG - Unzip image
2025-08-08 08:04:06,919 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:06,941 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-08 08:04:06,941 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-74583699']
2025-08-08 08:04:07,044 - INFO - Service template: ['default-domain', 'ctest-service_template_1-74583699'] deleted successfully.
2025-08-08 08:04:07,044 - INFO - Skip interface_detach for VM ctest-right_vm-90146520 in state BUILD
2025-08-08 08:04:07,044 - INFO - Deleting VM ctest-right_vm-90146520
2025-08-08 08:04:07,086 - ERROR - VM ctest-right_vm-90146520 has failed to come up
2025-08-08 08:04:07,086 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:07,086 - ERROR - VM ctest-right_vm-90146520 has failed to come up
2025-08-08 08:04:07,086 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:07,185 - INFO - Skip interface_detach for VM ctest-left_vm-45946519 in state BUILD
2025-08-08 08:04:07,185 - INFO - Deleting VM ctest-left_vm-45946519
2025-08-08 08:04:07,185 - ERROR - VM ctest-left_vm-45946519 has failed to come up
2025-08-08 08:04:07,185 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:07,185 - ERROR - VM ctest-left_vm-45946519 has failed to come up
2025-08-08 08:04:07,185 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:07,270 - INFO - Deleting VN ctest-right_vn-19654936
2025-08-08 08:04:07,320 - DEBUG - VN cccbbb5f-637d-4cec-a99b-04ffa9b77c0d still in use: Unable to complete operation on network cccbbb5f-637d-4cec-a99b-04ffa9b77c0d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-90b42805-410c-49cb-8bae-dc7b803c8010']
2025-08-08 08:04:07,320 - WARNING - Deleting VN ctest-right_vn-19654936 failed..Will retry
2025-08-08 08:04:09,551 - DEBUG - Response for deleting network ()
2025-08-08 08:04:09,551 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/d7e42373-f547-4c28-a848-705082468329
2025-08-08 08:04:09,563 - DEBUG - Response Code: 404
2025-08-08 08:04:09,564 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:09,577 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:09,616 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:09,762 - INFO - Validated that VN ctest-right_vn-19654936 is not found in API Server
2025-08-08 08:04:09,784 - DEBUG - VN ctest-right_vn-19654936 is not present in Agent 10.0.0.41
2025-08-08 08:04:09,817 - DEBUG - VN ctest-right_vn-19654936 is not present in Agent 10.0.0.27
2025-08-08 08:04:09,817 - INFO - Validated that VN ctest-right_vn-19654936 is not in any agent
2025-08-08 08:04:09,867 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-19654936 info
2025-08-08 08:04:09,867 - INFO - Deleting VN ctest-left_vn-69892377
2025-08-08 08:04:10,069 - DEBUG - Response for deleting network ()
2025-08-08 08:04:10,069 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c8fdb802-08a4-4394-8594-26365b330988
2025-08-08 08:04:10,089 - DEBUG - Response Code: 404
2025-08-08 08:04:10,090 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:10,100 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:10,117 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:10,225 - INFO - Validated that VN ctest-left_vn-69892377 is not found in API Server
2025-08-08 08:04:10,242 - DEBUG - VN ctest-left_vn-69892377 is not present in Agent 10.0.0.41
2025-08-08 08:04:10,270 - DEBUG - VN ctest-left_vn-69892377 is not present in Agent 10.0.0.27
2025-08-08 08:04:10,270 - INFO - Validated that VN ctest-left_vn-69892377 is not in any agent
2025-08-08 08:04:10,327 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-69892377 info
2025-08-08 08:04:10,327 - INFO - Deleting VN ctest-mgmt_vn-62888447
2025-08-08 08:04:10,500 - DEBUG - Response for deleting network ()
2025-08-08 08:04:10,500 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/53486531-a267-4c8f-82e5-ddd052c62316
2025-08-08 08:04:10,510 - DEBUG - Response Code: 404
2025-08-08 08:04:10,510 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:10,519 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:10,542 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:10,648 - INFO - Validated that VN ctest-mgmt_vn-62888447 is not found in API Server
2025-08-08 08:04:10,666 - DEBUG - VN ctest-mgmt_vn-62888447 is not present in Agent 10.0.0.41
2025-08-08 08:04:10,688 - DEBUG - VN ctest-mgmt_vn-62888447 is not present in Agent 10.0.0.27
2025-08-08 08:04:10,688 - INFO - Validated that VN ctest-mgmt_vn-62888447 is not in any agent
2025-08-08 08:04:10,736 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-62888447 info
2025-08-08 08:04:10,736 - INFO - Deleting VM ctest-vm-in-third-vn-66180389
2025-08-08 08:04:10,836 - INFO - Deleting VN ctest-third-vn-43916108
2025-08-08 08:04:10,887 - DEBUG - VN 1e9c94df-d948-45e2-a517-f715154ba436 still in use: Unable to complete operation on network 1e9c94df-d948-45e2-a517-f715154ba436. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4b4b06c7-7c88-40df-8f11-32c8a4e1fd42']
2025-08-08 08:04:10,887 - WARNING - Deleting VN ctest-third-vn-43916108 failed..Will retry
2025-08-08 08:04:13,093 - DEBUG - Response for deleting network ()
2025-08-08 08:04:14,432 - DEBUG - No XMPP flaps were noticed during the test
2025-08-08 08:04:14,432 - INFO -
2025-08-08 08:04:14,432 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:27]
2025-08-08 08:04:14,432 - INFO - --------------------------------------------------------------------------------
2025-08-08 08:04:14,436 - INFO - ================================================================================
2025-08-08 08:04:14,436 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-08 08:04:15,736 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-08 08:04:15,737 - INFO - Initial checks done. Running the testcase now
2025-08-08 08:04:15,737 - INFO -
2025-08-08 08:04:16,091 - DEBUG - Response for create_network : {'network': {'id': '69e2aeef-e691-42db-9f14-c4cec92876c5', 'name': 'ctest-mgmt_vn-05226319', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'project_id': '55dcc28e244c43188b2df923c721eddc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62164637', 'ctest-mgmt_vn-05226319'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:16,385 - DEBUG - Response for create_subnet : {'subnet': {'id': '73262013-4f66-4f6e-96aa-b4bb520b20c3', 'name': '', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'network_id': '69e2aeef-e691-42db-9f14-c4cec92876c5', 'ip_version': 4, 'cidr': '118.167.185.0/26', 'allocation_pools': [{'start': '118.167.185.2', 'end': '118.167.185.62'}], 'gateway_ip': '118.167.185.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '118.167.185.2', 'tags': [], 'project_id': '55dcc28e244c43188b2df923c721eddc'}}
2025-08-08 08:04:16,412 - INFO - Created VN ctest-mgmt_vn-05226319
2025-08-08 08:04:16,429 - DEBUG - VN ctest-mgmt_vn-05226319 UUID is 69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,537 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:16,548 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:16,573 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:16,718 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,740 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,764 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:16,789 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:16,801 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/ea9b26dd-7253-4cd5-abd1-43099fec30a7
2025-08-08 08:04:16,816 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:16,816 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,833 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:16,856 - INFO - Verified VN network id 15 for VN 69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,857 - INFO - Verifications in API Server for VN ctest-mgmt_vn-05226319 passed
2025-08-08 08:04:16,857 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:16,875 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:16,889 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:16,902 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/ea9b26dd-7253-4cd5-abd1-43099fec30a7
2025-08-08 08:04:16,924 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7629853064107737819', 'uuid-lslong': '11463003343981934277'}, 'enable': 'true', 'created': '2025-08-08T08:04:16', 'last-modified': '2025-08-08T08:04:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.616525'}
2025-08-08 08:04:16,932 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:16,941 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7629853064107737819', 'uuid-lslong': '11463003343981934277'}, 'enable': 'true', 'created': '2025-08-08T08:04:16', 'last-modified': '2025-08-08T08:04:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.601544'}
2025-08-08 08:04:16,951 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:16,960 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7629853064107737819', 'uuid-lslong': '11463003343981934277'}, 'enable': 'true', 'created': '2025-08-08T08:04:16', 'last-modified': '2025-08-08T08:04:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.650107'}
2025-08-08 08:04:16,970 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:16,970 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-05226319 passed
2025-08-08 08:04:16,971 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-05226319 in API_Server ======
2025-08-08 08:04:16,971 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:16,987 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:17,007 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:17,139 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/69e2aeef-e691-42db-9f14-c4cec92876c5
2025-08-08 08:04:17,164 - DEBUG - =>VN ctest-mgmt_vn-05226319 has no policy to be verified
2025-08-08 08:04:17,164 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:17,164 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:17,164 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:17,184 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319?flat'}
2025-08-08 08:04:17,184 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:17,184 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:17,204 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319?flat'}
2025-08-08 08:04:17,204 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:17,204 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:17,219 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319?flat'}
2025-08-08 08:04:17,219 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62164637:ctest-mgmt_vn-05226319 is found in opserver
2025-08-08 08:04:17,250 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:17,263 - DEBUG - VRF ids for VN ctest-mgmt_vn-05226319: {}
2025-08-08 08:04:17,569 - DEBUG - Response for create_network : {'network': {'id': 'f488c8c4-2a90-410f-9654-06c14bb7c619', 'name': 'ctest-left_vn-41186734', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'project_id': '55dcc28e244c43188b2df923c721eddc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62164637', 'ctest-left_vn-41186734'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:17,815 - DEBUG - Response for create_subnet : {'subnet': {'id': '1093be20-1774-4145-adca-7bf3eb62287e', 'name': '', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'network_id': 'f488c8c4-2a90-410f-9654-06c14bb7c619', 'ip_version': 4, 'cidr': '130.189.45.64/26', 'allocation_pools': [{'start': '130.189.45.66', 'end': '130.189.45.126'}], 'gateway_ip': '130.189.45.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.189.45.66', 'tags': [], 'project_id': '55dcc28e244c43188b2df923c721eddc'}}
2025-08-08 08:04:17,839 - INFO - Created VN ctest-left_vn-41186734
2025-08-08 08:04:17,855 - DEBUG - VN ctest-left_vn-41186734 UUID is f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:17,946 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:17,956 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:17,974 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:18,084 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,105 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,118 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:18,132 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:18,144 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3a371b70-e51a-45e7-ace8-092eca04b2a2
2025-08-08 08:04:18,155 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:18,155 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,170 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:18,191 - INFO - Verified VN network id 16 for VN f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,191 - INFO - Verifications in API Server for VN ctest-left_vn-41186734 passed
2025-08-08 08:04:18,192 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,207 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:18,218 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:18,244 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3a371b70-e51a-45e7-ace8-092eca04b2a2
2025-08-08 08:04:18,266 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17620554286940307727', 'uuid-lslong': '10832290431001675289'}, 'enable': 'true', 'created': '2025-08-08T08:04:17', 'last-modified': '2025-08-08T08:04:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517663'}
2025-08-08 08:04:18,275 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:18,285 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17620554286940307727', 'uuid-lslong': '10832290431001675289'}, 'enable': 'true', 'created': '2025-08-08T08:04:17', 'last-modified': '2025-08-08T08:04:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532757'}
2025-08-08 08:04:18,296 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:18,306 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17620554286940307727', 'uuid-lslong': '10832290431001675289'}, 'enable': 'true', 'created': '2025-08-08T08:04:17', 'last-modified': '2025-08-08T08:04:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.557607'}
2025-08-08 08:04:18,315 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:18,316 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-41186734 passed
2025-08-08 08:04:18,316 - DEBUG - ====Verifying policy data for ctest-left_vn-41186734 in API_Server ======
2025-08-08 08:04:18,316 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:18,329 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:18,348 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:18,471 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f488c8c4-2a90-410f-9654-06c14bb7c619
2025-08-08 08:04:18,493 - DEBUG - =>VN ctest-left_vn-41186734 has no policy to be verified
2025-08-08 08:04:18,494 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:18,494 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:18,494 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:18,513 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734?flat'}
2025-08-08 08:04:18,513 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:18,513 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:18,531 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734?flat'}
2025-08-08 08:04:18,532 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:18,532 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:18,550 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734?flat'}
2025-08-08 08:04:18,550 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62164637:ctest-left_vn-41186734 is found in opserver
2025-08-08 08:04:18,588 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:18,604 - DEBUG - VRF ids for VN ctest-left_vn-41186734: {}
2025-08-08 08:04:18,958 - DEBUG - Response for create_network : {'network': {'id': '58bf6378-bb50-442c-bfbc-abf89b70a9fa', 'name': 'ctest-right_vn-02694942', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'project_id': '55dcc28e244c43188b2df923c721eddc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62164637', 'ctest-right_vn-02694942'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:19,279 - DEBUG - Response for create_subnet : {'subnet': {'id': '74d18ba0-d98e-42d4-98c6-6f0680e102d0', 'name': '', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'network_id': '58bf6378-bb50-442c-bfbc-abf89b70a9fa', 'ip_version': 4, 'cidr': '204.168.12.192/26', 'allocation_pools': [{'start': '204.168.12.194', 'end': '204.168.12.254'}], 'gateway_ip': '204.168.12.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '204.168.12.194', 'tags': [], 'project_id': '55dcc28e244c43188b2df923c721eddc'}}
2025-08-08 08:04:19,308 - INFO - Created VN ctest-right_vn-02694942
2025-08-08 08:04:19,323 - DEBUG - VN ctest-right_vn-02694942 UUID is 58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,438 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:19,448 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:19,482 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:19,583 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,597 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,609 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:19,618 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:19,628 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b3c78df5-2932-4e72-86cc-a68c2e3de2db
2025-08-08 08:04:19,636 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:19,636 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,647 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:19,670 - INFO - Verified VN network id 17 for VN 58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,671 - INFO - Verifications in API Server for VN ctest-right_vn-02694942 passed
2025-08-08 08:04:19,671 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,684 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:19,694 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:19,706 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b3c78df5-2932-4e72-86cc-a68c2e3de2db
2025-08-08 08:04:19,729 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6394939366079218732', 'uuid-lslong': '13816106841113930234'}, 'enable': 'true', 'created': '2025-08-08T08:04:18', 'last-modified': '2025-08-08T08:04:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.515549'}
2025-08-08 08:04:19,739 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:19,748 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6394939366079218732', 'uuid-lslong': '13816106841113930234'}, 'enable': 'true', 'created': '2025-08-08T08:04:18', 'last-modified': '2025-08-08T08:04:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.540792'}
2025-08-08 08:04:19,758 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:19,767 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6394939366079218732', 'uuid-lslong': '13816106841113930234'}, 'enable': 'true', 'created': '2025-08-08T08:04:18', 'last-modified': '2025-08-08T08:04:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563748'}
2025-08-08 08:04:19,781 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:19,782 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-02694942 passed
2025-08-08 08:04:19,783 - DEBUG - ====Verifying policy data for ctest-right_vn-02694942 in API_Server ======
2025-08-08 08:04:19,783 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:19,795 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:19,816 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:19,960 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/58bf6378-bb50-442c-bfbc-abf89b70a9fa
2025-08-08 08:04:19,976 - DEBUG - =>VN ctest-right_vn-02694942 has no policy to be verified
2025-08-08 08:04:19,976 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:19,976 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:19,976 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:19,995 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942?flat'}
2025-08-08 08:04:19,995 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:19,996 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:20,018 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942?flat'}
2025-08-08 08:04:20,018 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:20,018 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:20,037 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942?flat'}
2025-08-08 08:04:20,037 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62164637:ctest-right_vn-02694942 is found in opserver
2025-08-08 08:04:20,068 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:20,085 - DEBUG - VRF ids for VN ctest-right_vn-02694942: {}
2025-08-08 08:04:20,387 - DEBUG - Response for create_network : {'network': {'id': '392c5e66-97a0-43d9-88d4-7481b9bf8802', 'name': 'ctest-trans_left_vn-13485850', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'project_id': '55dcc28e244c43188b2df923c721eddc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62164637', 'ctest-trans_left_vn-13485850'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:20,606 - DEBUG - Response for create_subnet : {'subnet': {'id': '534e7d35-78ca-42a0-a646-13d81df5b7dc', 'name': '', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'network_id': '392c5e66-97a0-43d9-88d4-7481b9bf8802', 'ip_version': 4, 'cidr': '166.16.101.192/26', 'allocation_pools': [{'start': '166.16.101.194', 'end': '166.16.101.254'}], 'gateway_ip': '166.16.101.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.16.101.194', 'tags': [], 'project_id': '55dcc28e244c43188b2df923c721eddc'}}
2025-08-08 08:04:20,632 - INFO - Created VN ctest-trans_left_vn-13485850
2025-08-08 08:04:20,646 - DEBUG - VN ctest-trans_left_vn-13485850 UUID is 392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,732 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:20,739 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:20,758 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:20,860 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,874 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,887 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:20,899 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:20,909 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b9801d0e-b589-4551-becd-5de7b1b1e4fa
2025-08-08 08:04:20,922 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-08 08:04:20,922 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,934 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:20,955 - INFO - Verified VN network id 18 for VN 392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,955 - INFO - Verifications in API Server for VN ctest-trans_left_vn-13485850 passed
2025-08-08 08:04:20,955 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:20,967 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:20,977 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:20,990 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b9801d0e-b589-4551-becd-5de7b1b1e4fa
2025-08-08 08:04:21,013 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4119771553860699097', 'uuid-lslong': '9859633584736798722'}, 'enable': 'true', 'created': '2025-08-08T08:04:20', 'last-modified': '2025-08-08T08:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470770'}
2025-08-08 08:04:21,031 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-08 08:04:21,042 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4119771553860699097', 'uuid-lslong': '9859633584736798722'}, 'enable': 'true', 'created': '2025-08-08T08:04:20', 'last-modified': '2025-08-08T08:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.496190'}
2025-08-08 08:04:21,055 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-08 08:04:21,063 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4119771553860699097', 'uuid-lslong': '9859633584736798722'}, 'enable': 'true', 'created': '2025-08-08T08:04:20', 'last-modified': '2025-08-08T08:04:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520950'}
2025-08-08 08:04:21,073 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-08 08:04:21,074 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-13485850 passed
2025-08-08 08:04:21,074 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-13485850 in API_Server ======
2025-08-08 08:04:21,074 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:21,086 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:21,112 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:21,225 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/392c5e66-97a0-43d9-88d4-7481b9bf8802
2025-08-08 08:04:21,242 - DEBUG - =>VN ctest-trans_left_vn-13485850 has no policy to be verified
2025-08-08 08:04:21,242 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:21,242 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:21,242 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:21,261 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850?flat'}
2025-08-08 08:04:21,261 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:21,261 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:21,279 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850?flat'}
2025-08-08 08:04:21,279 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:21,279 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:21,296 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850?flat'}
2025-08-08 08:04:21,296 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62164637:ctest-trans_left_vn-13485850 is found in opserver
2025-08-08 08:04:21,324 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:21,339 - DEBUG - VRF ids for VN ctest-trans_left_vn-13485850: {}
2025-08-08 08:04:21,661 - DEBUG - Response for create_network : {'network': {'id': '3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97', 'name': 'ctest-trans_right_vn-71971201', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'project_id': '55dcc28e244c43188b2df923c721eddc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-62164637', 'ctest-trans_right_vn-71971201'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:21,888 - DEBUG - Response for create_subnet : {'subnet': {'id': '83a40679-ad3a-4461-afb9-32888cfa403a', 'name': '', 'tenant_id': '55dcc28e244c43188b2df923c721eddc', 'network_id': '3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97', 'ip_version': 4, 'cidr': '202.175.221.0/26', 'allocation_pools': [{'start': '202.175.221.2', 'end': '202.175.221.62'}], 'gateway_ip': '202.175.221.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '202.175.221.2', 'tags': [], 'project_id': '55dcc28e244c43188b2df923c721eddc'}}
2025-08-08 08:04:21,916 - INFO - Created VN ctest-trans_right_vn-71971201
2025-08-08 08:04:21,934 - DEBUG - VN ctest-trans_right_vn-71971201 UUID is 3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,023 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:22,033 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:22,051 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:22,153 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,172 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,184 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:22,194 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:22,204 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b1474378-59bd-4b51-8088-523b99bc7b16
2025-08-08 08:04:22,215 - DEBUG - Route Targets: ['target:64512:8000010']
2025-08-08 08:04:22,215 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,230 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:22,254 - INFO - Verified VN network id 19 for VN 3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,254 - INFO - Verifications in API Server for VN ctest-trans_right_vn-71971201 passed
2025-08-08 08:04:22,254 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,266 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:22,276 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:22,286 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b1474378-59bd-4b51-8088-523b99bc7b16
2025-08-08 08:04:22,304 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4298638904003609325', 'uuid-lslong': '10063129749550959767'}, 'enable': 'true', 'created': '2025-08-08T08:04:21', 'last-modified': '2025-08-08T08:04:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490524'}
2025-08-08 08:04:22,314 - DEBUG - Route Targets: ['target:64512:8000010']
2025-08-08 08:04:22,322 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4298638904003609325', 'uuid-lslong': '10063129749550959767'}, 'enable': 'true', 'created': '2025-08-08T08:04:21', 'last-modified': '2025-08-08T08:04:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.494648'}
2025-08-08 08:04:22,331 - DEBUG - Route Targets: ['target:64512:8000010']
2025-08-08 08:04:22,339 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-62164637', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4298638904003609325', 'uuid-lslong': '10063129749550959767'}, 'enable': 'true', 'created': '2025-08-08T08:04:21', 'last-modified': '2025-08-08T08:04:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520780'}
2025-08-08 08:04:22,346 - DEBUG - Route Targets: ['target:64512:8000010']
2025-08-08 08:04:22,346 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-71971201 passed
2025-08-08 08:04:22,347 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-71971201 in API_Server ======
2025-08-08 08:04:22,347 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:22,355 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:22,371 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:22,479 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/3ba7d54e-dacf-42ed-8ba7-6b30c2a68c97
2025-08-08 08:04:22,494 - DEBUG - =>VN ctest-trans_right_vn-71971201 has no policy to be verified
2025-08-08 08:04:22,494 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:22,494 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:22,494 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:22,515 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201?flat'}
2025-08-08 08:04:22,515 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:22,515 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:22,539 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201?flat'}
2025-08-08 08:04:22,539 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:22,539 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:22,559 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201?flat'}
2025-08-08 08:04:22,559 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-62164637:ctest-trans_right_vn-71971201 is found in opserver
2025-08-08 08:04:22,596 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:22,616 - DEBUG - VRF ids for VN ctest-trans_right_vn-71971201: {}
2025-08-08 08:04:23,746 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-2)
2025-08-08 08:04:25,263 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-1)
2025-08-08 08:04:25,263 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-06878907']
2025-08-08 08:04:25,565 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-06878907'] created succesfully
2025-08-08 08:04:25,921 - DEBUG - Image by name tiny_trans_fw not found
2025-08-08 08:04:25,921 - DEBUG - Installing image tiny_trans_fw
2025-08-08 08:04:25,921 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-08 08:04:25,922 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-08 08:04:25,922 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-08 08:04:25,944 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-08 08:04:26,008 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-08 08:04:26,008 - DEBUG - Unzip image
2025-08-08 08:04:26,008 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-08 08:04:26,040 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-08 08:04:26,041 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-06878907']
2025-08-08 08:04:26,140 - INFO - Service template: ['default-domain', 'ctest-service_template_1-06878907'] deleted successfully.
2025-08-08 08:04:26,140 - INFO - Skip interface_detach for VM ctest-right_vm-64784267 in state BUILD
2025-08-08 08:04:26,140 - INFO - Deleting VM ctest-right_vm-64784267
2025-08-08 08:04:26,141 - ERROR - VM ctest-right_vm-64784267 has failed to come up
2025-08-08 08:04:26,141 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:26,141 - ERROR - VM ctest-right_vm-64784267 has failed to come up
2025-08-08 08:04:26,141 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:26,277 - INFO - Skip interface_detach for VM ctest-left_vm-26814033 in state BUILD
2025-08-08 08:04:26,279 - INFO - Deleting VM ctest-left_vm-26814033
2025-08-08 08:04:26,280 - ERROR - VM ctest-left_vm-26814033 has failed to come up
2025-08-08 08:04:26,280 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:26,281 - ERROR - VM ctest-left_vm-26814033 has failed to come up
2025-08-08 08:04:26,281 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:26,400 - INFO - Deleting VN ctest-trans_right_vn-71971201
2025-08-08 08:04:26,604 - DEBUG - Response for deleting network ()
2025-08-08 08:04:26,604 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c0975598-d881-405e-8f51-c7432be899a8
2025-08-08 08:04:26,612 - DEBUG - Response Code: 404
2025-08-08 08:04:26,613 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:26,620 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:26,681 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:26,785 - INFO - Validated that VN ctest-trans_right_vn-71971201 is not found in API Server
2025-08-08 08:04:26,827 - DEBUG - VN ctest-trans_right_vn-71971201 is not present in Agent 10.0.0.41
2025-08-08 08:04:26,845 - DEBUG - VN ctest-trans_right_vn-71971201 is not present in Agent 10.0.0.27
2025-08-08 08:04:26,845 - INFO - Validated that VN ctest-trans_right_vn-71971201 is not in any agent
2025-08-08 08:04:26,910 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-71971201 info
2025-08-08 08:04:26,911 - INFO - Deleting VN ctest-trans_left_vn-13485850
2025-08-08 08:04:27,120 - DEBUG - Response for deleting network ()
2025-08-08 08:04:27,120 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a81d4df5-d160-4e64-bc24-1a4107582c3d
2025-08-08 08:04:27,136 - DEBUG - Response Code: 404
2025-08-08 08:04:27,136 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:27,150 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:27,181 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:27,288 - INFO - Validated that VN ctest-trans_left_vn-13485850 is not found in API Server
2025-08-08 08:04:27,302 - DEBUG - VN ctest-trans_left_vn-13485850 is not present in Agent 10.0.0.41
2025-08-08 08:04:27,318 - DEBUG - VN ctest-trans_left_vn-13485850 is not present in Agent 10.0.0.27
2025-08-08 08:04:27,318 - INFO - Validated that VN ctest-trans_left_vn-13485850 is not in any agent
2025-08-08 08:04:27,380 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-13485850 info
2025-08-08 08:04:27,380 - INFO - Deleting VN ctest-right_vn-02694942
2025-08-08 08:04:27,437 - DEBUG - VN 58bf6378-bb50-442c-bfbc-abf89b70a9fa still in use: Unable to complete operation on network 58bf6378-bb50-442c-bfbc-abf89b70a9fa. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-02d5392f-12f7-4c17-a2c8-f91318a51c8a']
2025-08-08 08:04:27,437 - WARNING - Deleting VN ctest-right_vn-02694942 failed..Will retry
2025-08-08 08:04:29,643 - DEBUG - Response for deleting network ()
2025-08-08 08:04:29,643 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/ba7f1731-36fd-46b6-96a1-e51078b0032e
2025-08-08 08:04:29,653 - DEBUG - Response Code: 404
2025-08-08 08:04:29,653 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:29,676 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:29,697 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:29,805 - INFO - Validated that VN ctest-right_vn-02694942 is not found in API Server
2025-08-08 08:04:29,819 - DEBUG - VN ctest-right_vn-02694942 is not present in Agent 10.0.0.41
2025-08-08 08:04:29,832 - DEBUG - VN ctest-right_vn-02694942 is not present in Agent 10.0.0.27
2025-08-08 08:04:29,832 - INFO - Validated that VN ctest-right_vn-02694942 is not in any agent
2025-08-08 08:04:29,873 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-02694942 info
2025-08-08 08:04:29,873 - INFO - Deleting VN ctest-left_vn-41186734
2025-08-08 08:04:30,040 - DEBUG - Response for deleting network ()
2025-08-08 08:04:30,040 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/e757dcd3-1682-426c-b22f-a2ad35958daf
2025-08-08 08:04:30,049 - DEBUG - Response Code: 404
2025-08-08 08:04:30,049 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:30,056 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:30,072 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:30,176 - INFO - Validated that VN ctest-left_vn-41186734 is not found in API Server
2025-08-08 08:04:30,193 - DEBUG - VN ctest-left_vn-41186734 is not present in Agent 10.0.0.41
2025-08-08 08:04:30,207 - DEBUG - VN ctest-left_vn-41186734 is not present in Agent 10.0.0.27
2025-08-08 08:04:30,207 - INFO - Validated that VN ctest-left_vn-41186734 is not in any agent
2025-08-08 08:04:30,249 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-41186734 info
2025-08-08 08:04:30,249 - INFO - Deleting VN ctest-mgmt_vn-05226319
2025-08-08 08:04:30,446 - DEBUG - Response for deleting network ()
2025-08-08 08:04:30,446 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/87718fe9-c78e-4d00-863b-52bc767055b9
2025-08-08 08:04:30,455 - DEBUG - Response Code: 404
2025-08-08 08:04:30,455 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:30,473 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:30,500 - DEBUG - Requesting: http://10.0.0.50:8082/project/55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:30,616 - INFO - Validated that VN ctest-mgmt_vn-05226319 is not found in API Server
2025-08-08 08:04:30,630 - DEBUG - VN ctest-mgmt_vn-05226319 is not present in Agent 10.0.0.41
2025-08-08 08:04:30,643 - DEBUG - VN ctest-mgmt_vn-05226319 is not present in Agent 10.0.0.27
2025-08-08 08:04:30,643 - INFO - Validated that VN ctest-mgmt_vn-05226319 is not in any agent
2025-08-08 08:04:30,696 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-05226319 info
2025-08-08 08:04:32,004 - DEBUG - No XMPP flaps were noticed during the test
2025-08-08 08:04:32,005 - INFO -
2025-08-08 08:04:32,005 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2025-08-08 08:04:32,005 - INFO - --------------------------------------------------------------------------------
2025-08-08 08:04:32,765 - INFO - Deleted project: ctest-TestSvcRegr-62164637, ID : 55dcc28e-244c-4318-8b2d-f923c721eddc
2025-08-08 08:04:32,997 - INFO - Domain Default found not creating
2025-08-08 08:04:33,186 - INFO - Project ctest-TestSvcRegr-86431188 not found, creating it
2025-08-08 08:04:33,748 - INFO - Created Project:ctest-TestSvcRegr-86431188, ID : 996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:35,292 - INFO - ================================================================================
2025-08-08 08:04:35,292 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-08 08:04:36,605 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-08 08:04:36,605 - INFO - Initial checks done. Running the testcase now
2025-08-08 08:04:36,605 - INFO -
2025-08-08 08:04:37,397 - DEBUG - Response for create_network : {'network': {'id': '17b04af3-d647-40cf-be42-cd3818cbd231', 'name': 'ctest-mgmt_vn-19353433', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'ctest-mgmt_vn-19353433'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:37,742 - DEBUG - Response for create_subnet : {'subnet': {'id': '4b467082-014c-4ada-baac-99491a95173d', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': '17b04af3-d647-40cf-be42-cd3818cbd231', 'ip_version': 4, 'cidr': '158.101.124.64/26', 'allocation_pools': [{'start': '158.101.124.66', 'end': '158.101.124.126'}], 'gateway_ip': '158.101.124.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '158.101.124.66', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:37,781 - INFO - Created VN ctest-mgmt_vn-19353433
2025-08-08 08:04:37,794 - DEBUG - VN ctest-mgmt_vn-19353433 UUID is 17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:37,882 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:38,188 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:38,221 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:38,259 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:38,355 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,370 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,382 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:38,395 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:38,406 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/7e0be0e6-ff0e-4c8c-93ad-f14f6dcc8986
2025-08-08 08:04:38,421 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:38,421 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,432 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:38,450 - INFO - Verified VN network id 15 for VN 17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,450 - INFO - Verifications in API Server for VN ctest-mgmt_vn-19353433 passed
2025-08-08 08:04:38,450 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,463 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:38,475 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:38,485 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/7e0be0e6-ff0e-4c8c-93ad-f14f6dcc8986
2025-08-08 08:04:38,500 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1706946669905920207', 'uuid-lslong': '13709745856487084593'}, 'enable': 'true', 'created': '2025-08-08T08:04:37', 'last-modified': '2025-08-08T08:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.836674'}
2025-08-08 08:04:38,507 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:38,514 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1706946669905920207', 'uuid-lslong': '13709745856487084593'}, 'enable': 'true', 'created': '2025-08-08T08:04:37', 'last-modified': '2025-08-08T08:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.852269'}
2025-08-08 08:04:38,522 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:38,528 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1706946669905920207', 'uuid-lslong': '13709745856487084593'}, 'enable': 'true', 'created': '2025-08-08T08:04:37', 'last-modified': '2025-08-08T08:04:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.864226'}
2025-08-08 08:04:38,537 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:38,537 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-19353433 passed
2025-08-08 08:04:38,538 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-19353433 in API_Server ======
2025-08-08 08:04:38,538 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:38,546 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:38,561 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:38,667 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/17b04af3-d647-40cf-be42-cd3818cbd231
2025-08-08 08:04:38,684 - DEBUG - =>VN ctest-mgmt_vn-19353433 has no policy to be verified
2025-08-08 08:04:38,684 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:38,684 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:38,684 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:38,983 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:39,061 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433?flat'}
2025-08-08 08:04:39,061 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:39,061 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:39,366 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:39,442 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433?flat'}
2025-08-08 08:04:39,442 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:39,442 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:39,765 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:39,834 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433?flat'}
2025-08-08 08:04:39,834 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-19353433 is found in opserver
2025-08-08 08:04:39,886 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:39,904 - DEBUG - VRF ids for VN ctest-mgmt_vn-19353433: {}
2025-08-08 08:04:40,246 - DEBUG - Response for create_network : {'network': {'id': 'fa4a1aad-ed08-4689-b6a1-56d9a3b30902', 'name': 'service-left-vn-86981939', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'service-left-vn-86981939'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:40,496 - DEBUG - Response for create_subnet : {'subnet': {'id': '1e158a93-ed2e-4983-b62b-f2596952761f', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': 'fa4a1aad-ed08-4689-b6a1-56d9a3b30902', 'ip_version': 4, 'cidr': '74.34.0.0/26', 'allocation_pools': [{'start': '74.34.0.2', 'end': '74.34.0.62'}], 'gateway_ip': '74.34.0.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.34.0.2', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:40,544 - INFO - Created VN service-left-vn-86981939
2025-08-08 08:04:40,571 - DEBUG - VN service-left-vn-86981939 UUID is fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,654 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:40,662 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:40,705 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:40,807 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,827 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,839 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:40,856 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:40,866 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/c317009d-413a-4d9e-993c-c59c8c117877
2025-08-08 08:04:40,875 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:40,875 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,890 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:40,913 - INFO - Verified VN network id 16 for VN fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,913 - INFO - Verifications in API Server for VN service-left-vn-86981939 passed
2025-08-08 08:04:40,913 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:40,926 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:40,936 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:40,946 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/c317009d-413a-4d9e-993c-c59c8c117877
2025-08-08 08:04:40,961 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18035256992066979465', 'uuid-lslong': '13159895078907611394'}, 'enable': 'true', 'created': '2025-08-08T08:04:40', 'last-modified': '2025-08-08T08:04:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512512'}
2025-08-08 08:04:40,969 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:40,976 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18035256992066979465', 'uuid-lslong': '13159895078907611394'}, 'enable': 'true', 'created': '2025-08-08T08:04:40', 'last-modified': '2025-08-08T08:04:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.539539'}
2025-08-08 08:04:40,985 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:40,993 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18035256992066979465', 'uuid-lslong': '13159895078907611394'}, 'enable': 'true', 'created': '2025-08-08T08:04:40', 'last-modified': '2025-08-08T08:04:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.552661'}
2025-08-08 08:04:41,001 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:41,001 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-86981939 passed
2025-08-08 08:04:41,001 - DEBUG - ====Verifying policy data for service-left-vn-86981939 in API_Server ======
2025-08-08 08:04:41,001 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:41,011 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:41,028 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:41,150 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/fa4a1aad-ed08-4689-b6a1-56d9a3b30902
2025-08-08 08:04:41,165 - DEBUG - =>VN service-left-vn-86981939 has no policy to be verified
2025-08-08 08:04:41,165 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:41,166 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:41,166 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:41,253 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939?flat'}
2025-08-08 08:04:41,253 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:41,253 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:41,318 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939?flat'}
2025-08-08 08:04:41,318 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:41,318 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:41,393 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939?flat'}
2025-08-08 08:04:41,393 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:service-left-vn-86981939 is found in opserver
2025-08-08 08:04:41,429 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:41,448 - DEBUG - VRF ids for VN service-left-vn-86981939: {}
2025-08-08 08:04:41,772 - DEBUG - Response for create_network : {'network': {'id': 'ca57f4d7-2536-4978-94e8-0dad43c44cc2', 'name': 'service-right-vn-02191375', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'service-right-vn-02191375'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:42,019 - DEBUG - Response for create_subnet : {'subnet': {'id': '7fb9cad0-2d14-4dff-9067-01d32e0e391f', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': 'ca57f4d7-2536-4978-94e8-0dad43c44cc2', 'ip_version': 4, 'cidr': '187.121.173.0/26', 'allocation_pools': [{'start': '187.121.173.2', 'end': '187.121.173.62'}], 'gateway_ip': '187.121.173.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '187.121.173.2', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:42,046 - INFO - Created VN service-right-vn-02191375
2025-08-08 08:04:42,061 - DEBUG - VN service-right-vn-02191375 UUID is ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,149 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:42,157 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:42,171 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:42,283 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,310 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,325 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:42,335 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:42,347 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/6eed829f-f2ae-43ee-8e7a-b6796b1aaf15
2025-08-08 08:04:42,358 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:42,358 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,370 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:42,390 - INFO - Verified VN network id 17 for VN ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,390 - INFO - Verifications in API Server for VN service-right-vn-02191375 passed
2025-08-08 08:04:42,391 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,403 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:42,412 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:42,422 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/6eed829f-f2ae-43ee-8e7a-b6796b1aaf15
2025-08-08 08:04:42,436 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14580391523514730872', 'uuid-lslong': '10729841150027648194'}, 'enable': 'true', 'created': '2025-08-08T08:04:41', 'last-modified': '2025-08-08T08:04:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484080'}
2025-08-08 08:04:42,445 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:42,452 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14580391523514730872', 'uuid-lslong': '10729841150027648194'}, 'enable': 'true', 'created': '2025-08-08T08:04:41', 'last-modified': '2025-08-08T08:04:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483525'}
2025-08-08 08:04:42,460 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:42,467 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14580391523514730872', 'uuid-lslong': '10729841150027648194'}, 'enable': 'true', 'created': '2025-08-08T08:04:41', 'last-modified': '2025-08-08T08:04:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.514757'}
2025-08-08 08:04:42,474 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:42,474 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-02191375 passed
2025-08-08 08:04:42,474 - DEBUG - ====Verifying policy data for service-right-vn-02191375 in API_Server ======
2025-08-08 08:04:42,475 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:42,484 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:42,500 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:42,604 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/ca57f4d7-2536-4978-94e8-0dad43c44cc2
2025-08-08 08:04:42,620 - DEBUG - =>VN service-right-vn-02191375 has no policy to be verified
2025-08-08 08:04:42,620 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:42,620 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:42,621 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:42,685 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375?flat'}
2025-08-08 08:04:42,685 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:42,685 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:42,758 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375?flat'}
2025-08-08 08:04:42,758 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:42,758 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:42,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375?flat'}
2025-08-08 08:04:42,829 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:service-right-vn-02191375 is found in opserver
2025-08-08 08:04:42,864 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:42,877 - DEBUG - VRF ids for VN service-right-vn-02191375: {}
2025-08-08 08:04:42,965 - DEBUG - Services list from nova: [, , , ]
2025-08-08 08:04:44,161 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-2)
2025-08-08 08:04:45,615 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-1)
2025-08-08 08:04:45,615 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-58764995']
2025-08-08 08:04:45,918 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-58764995'] created succesfully
2025-08-08 08:04:46,332 - DEBUG - Image by name tiny_nat_fw not found
2025-08-08 08:04:46,332 - DEBUG - Installing image tiny_nat_fw
2025-08-08 08:04:46,332 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-08 08:04:46,332 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:46,332 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:46,332 - DEBUG - Unzip image
2025-08-08 08:04:46,332 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:04:46,360 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-08 08:04:46,360 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-58764995']
2025-08-08 08:04:46,500 - INFO - Service template: ['default-domain', 'ctest-service_template_1-58764995'] deleted successfully.
2025-08-08 08:04:46,500 - INFO - Skip interface_detach for VM ctest-right_vm-17738487 in state BUILD
2025-08-08 08:04:46,500 - INFO - Deleting VM ctest-right_vm-17738487
2025-08-08 08:04:46,545 - ERROR - VM ctest-right_vm-17738487 has failed to come up
2025-08-08 08:04:46,546 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:46,546 - ERROR - VM ctest-right_vm-17738487 has failed to come up
2025-08-08 08:04:46,546 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:46,672 - INFO - Skip interface_detach for VM ctest-left_vm-97213651 in state BUILD
2025-08-08 08:04:46,673 - INFO - Deleting VM ctest-left_vm-97213651
2025-08-08 08:04:46,673 - ERROR - VM ctest-left_vm-97213651 has failed to come up
2025-08-08 08:04:46,673 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:46,673 - ERROR - VM ctest-left_vm-97213651 has failed to come up
2025-08-08 08:04:46,674 - ERROR - Nova failed to get host of the VM
2025-08-08 08:04:46,791 - INFO - Deleting VN service-right-vn-02191375
2025-08-08 08:04:46,855 - DEBUG - VN ca57f4d7-2536-4978-94e8-0dad43c44cc2 still in use: Unable to complete operation on network ca57f4d7-2536-4978-94e8-0dad43c44cc2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-abf19305-f589-4ef3-802e-4bafb127b957']
2025-08-08 08:04:46,855 - WARNING - Deleting VN service-right-vn-02191375 failed..Will retry
2025-08-08 08:04:49,050 - DEBUG - Response for deleting network ()
2025-08-08 08:04:49,050 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6b50e6e6-f72d-4cba-a412-aab436c5727f
2025-08-08 08:04:49,071 - DEBUG - Response Code: 404
2025-08-08 08:04:49,072 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:49,086 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:49,107 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:49,224 - INFO - Validated that VN service-right-vn-02191375 is not found in API Server
2025-08-08 08:04:49,239 - DEBUG - VN service-right-vn-02191375 is not present in Agent 10.0.0.41
2025-08-08 08:04:49,255 - DEBUG - VN service-right-vn-02191375 is not present in Agent 10.0.0.27
2025-08-08 08:04:49,255 - INFO - Validated that VN service-right-vn-02191375 is not in any agent
2025-08-08 08:04:49,294 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-02191375 info
2025-08-08 08:04:49,294 - INFO - Deleting VN service-left-vn-86981939
2025-08-08 08:04:49,548 - DEBUG - Response for deleting network ()
2025-08-08 08:04:49,548 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/9caa5299-2375-4416-b4ad-bbec4dff652d
2025-08-08 08:04:49,562 - DEBUG - Response Code: 404
2025-08-08 08:04:49,562 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:49,570 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:49,584 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:49,682 - INFO - Validated that VN service-left-vn-86981939 is not found in API Server
2025-08-08 08:04:49,696 - DEBUG - VN service-left-vn-86981939 is not present in Agent 10.0.0.41
2025-08-08 08:04:49,711 - DEBUG - VN service-left-vn-86981939 is not present in Agent 10.0.0.27
2025-08-08 08:04:49,711 - INFO - Validated that VN service-left-vn-86981939 is not in any agent
2025-08-08 08:04:49,751 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-86981939 info
2025-08-08 08:04:49,752 - INFO - Deleting VN ctest-mgmt_vn-19353433
2025-08-08 08:04:49,955 - DEBUG - Response for deleting network ()
2025-08-08 08:04:49,955 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/28c7f462-632e-4e19-91d7-98cb8f1ea8a5
2025-08-08 08:04:49,978 - DEBUG - Response Code: 404
2025-08-08 08:04:49,978 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:49,988 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:50,005 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:50,132 - INFO - Validated that VN ctest-mgmt_vn-19353433 is not found in API Server
2025-08-08 08:04:50,146 - DEBUG - VN ctest-mgmt_vn-19353433 is not present in Agent 10.0.0.41
2025-08-08 08:04:50,160 - DEBUG - VN ctest-mgmt_vn-19353433 is not present in Agent 10.0.0.27
2025-08-08 08:04:50,160 - INFO - Validated that VN ctest-mgmt_vn-19353433 is not in any agent
2025-08-08 08:04:50,200 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-19353433 info
2025-08-08 08:04:51,500 - DEBUG - No XMPP flaps were noticed during the test
2025-08-08 08:04:51,500 - INFO -
2025-08-08 08:04:51,500 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-08-08 08:04:51,501 - INFO - --------------------------------------------------------------------------------
2025-08-08 08:04:51,505 - INFO - ================================================================================
2025-08-08 08:04:51,505 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-08 08:04:52,810 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-08 08:04:52,810 - INFO - Initial checks done. Running the testcase now
2025-08-08 08:04:52,810 - INFO -
2025-08-08 08:04:53,180 - DEBUG - Response for create_network : {'network': {'id': '382caf52-6543-430a-ba35-e000c1b90747', 'name': 'ctest-mgmt_vn-57576902', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'ctest-mgmt_vn-57576902'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:53,486 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cfab2c86-fe62-4c08-b6bd-87db9fac4bcd', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': '382caf52-6543-430a-ba35-e000c1b90747', 'ip_version': 4, 'cidr': '26.32.0.64/26', 'allocation_pools': [{'start': '26.32.0.66', 'end': '26.32.0.126'}], 'gateway_ip': '26.32.0.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.32.0.66', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:53,509 - INFO - Created VN ctest-mgmt_vn-57576902
2025-08-08 08:04:53,526 - DEBUG - VN ctest-mgmt_vn-57576902 UUID is 382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,603 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:53,612 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:53,627 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:53,725 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,744 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,755 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:04:53,766 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:04:53,777 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/319a7436-ce8a-4734-911c-8bd12eccadab
2025-08-08 08:04:53,787 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:53,787 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,799 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:04:53,820 - INFO - Verified VN network id 15 for VN 382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,820 - INFO - Verifications in API Server for VN ctest-mgmt_vn-57576902 passed
2025-08-08 08:04:53,821 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:53,833 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:04:53,845 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:04:53,855 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/319a7436-ce8a-4734-911c-8bd12eccadab
2025-08-08 08:04:53,873 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4047802933520319242', 'uuid-lslong': '13417876958675011399'}, 'enable': 'true', 'created': '2025-08-08T08:04:53', 'last-modified': '2025-08-08T08:04:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.451621'}
2025-08-08 08:04:53,881 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:53,888 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4047802933520319242', 'uuid-lslong': '13417876958675011399'}, 'enable': 'true', 'created': '2025-08-08T08:04:53', 'last-modified': '2025-08-08T08:04:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.475545'}
2025-08-08 08:04:53,895 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:53,904 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4047802933520319242', 'uuid-lslong': '13417876958675011399'}, 'enable': 'true', 'created': '2025-08-08T08:04:53', 'last-modified': '2025-08-08T08:04:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.481152'}
2025-08-08 08:04:53,913 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-08 08:04:53,913 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-57576902 passed
2025-08-08 08:04:53,913 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-57576902 in API_Server ======
2025-08-08 08:04:53,914 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:53,924 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:53,944 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:54,056 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/382caf52-6543-430a-ba35-e000c1b90747
2025-08-08 08:04:54,072 - DEBUG - =>VN ctest-mgmt_vn-57576902 has no policy to be verified
2025-08-08 08:04:54,072 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:54,072 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:54,072 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:54,088 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902?flat'}
2025-08-08 08:04:54,088 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:54,088 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:54,105 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902?flat'}
2025-08-08 08:04:54,105 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:54,106 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:54,123 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902?flat'}
2025-08-08 08:04:54,123 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:ctest-mgmt_vn-57576902 is found in opserver
2025-08-08 08:04:54,160 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:54,177 - DEBUG - VRF ids for VN ctest-mgmt_vn-57576902: {}
2025-08-08 08:04:54,477 - DEBUG - Response for create_network : {'network': {'id': '478fe42b-8b12-472a-8b05-3698853f530d', 'name': 'ctest-left_vn-61757867', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'ctest-left_vn-61757867'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:54,708 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bfe76712-efd9-4466-9e7a-fd344447fcd9', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': '478fe42b-8b12-472a-8b05-3698853f530d', 'ip_version': 4, 'cidr': '166.172.216.128/26', 'allocation_pools': [{'start': '166.172.216.130', 'end': '166.172.216.190'}], 'gateway_ip': '166.172.216.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.172.216.130', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:54,733 - INFO - Created VN ctest-left_vn-61757867
2025-08-08 08:04:54,745 - DEBUG - VN ctest-left_vn-61757867 UUID is 478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:54,839 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:54,848 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:54,863 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:54,961 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:54,983 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:54,996 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:04:55,009 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:04:55,018 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bb024ae3-b4ec-4e4b-a083-639a5a556ac4
2025-08-08 08:04:55,029 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:55,029 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:55,044 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:04:55,065 - INFO - Verified VN network id 16 for VN 478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:55,065 - INFO - Verifications in API Server for VN ctest-left_vn-61757867 passed
2025-08-08 08:04:55,065 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:55,078 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:04:55,089 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:04:55,101 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/bb024ae3-b4ec-4e4b-a083-639a5a556ac4
2025-08-08 08:04:55,119 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5156590974030464810', 'uuid-lslong': '10017472974853985037'}, 'enable': 'true', 'created': '2025-08-08T08:04:54', 'last-modified': '2025-08-08T08:04:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.472555'}
2025-08-08 08:04:55,126 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:55,133 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5156590974030464810', 'uuid-lslong': '10017472974853985037'}, 'enable': 'true', 'created': '2025-08-08T08:04:54', 'last-modified': '2025-08-08T08:04:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483673'}
2025-08-08 08:04:55,141 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:55,147 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5156590974030464810', 'uuid-lslong': '10017472974853985037'}, 'enable': 'true', 'created': '2025-08-08T08:04:54', 'last-modified': '2025-08-08T08:04:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504192'}
2025-08-08 08:04:55,155 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-08 08:04:55,155 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-61757867 passed
2025-08-08 08:04:55,156 - DEBUG - ====Verifying policy data for ctest-left_vn-61757867 in API_Server ======
2025-08-08 08:04:55,156 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:55,164 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:55,181 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:55,296 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/478fe42b-8b12-472a-8b05-3698853f530d
2025-08-08 08:04:55,314 - DEBUG - =>VN ctest-left_vn-61757867 has no policy to be verified
2025-08-08 08:04:55,314 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:55,314 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:55,314 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:55,328 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867?flat'}
2025-08-08 08:04:55,328 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:55,328 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:55,344 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867?flat'}
2025-08-08 08:04:55,344 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:55,344 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:55,358 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867?flat'}
2025-08-08 08:04:55,358 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:ctest-left_vn-61757867 is found in opserver
2025-08-08 08:04:55,390 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:55,404 - DEBUG - VRF ids for VN ctest-left_vn-61757867: {}
2025-08-08 08:04:55,732 - DEBUG - Response for create_network : {'network': {'id': 'f8db9ec7-7554-4ded-b3d1-cde3e47a6854', 'name': 'ctest-right_vn-42943813', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'project_id': '996c75dce5a94565a0ac8977c07cc749', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-86431188', 'ctest-right_vn-42943813'], 'port_security_enabled': True, 'description': ''}}
2025-08-08 08:04:56,017 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b9fbba19-f937-4906-a150-7f1ea8369f3d', 'name': '', 'tenant_id': '996c75dce5a94565a0ac8977c07cc749', 'network_id': 'f8db9ec7-7554-4ded-b3d1-cde3e47a6854', 'ip_version': 4, 'cidr': '110.255.44.0/26', 'allocation_pools': [{'start': '110.255.44.2', 'end': '110.255.44.62'}], 'gateway_ip': '110.255.44.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '110.255.44.2', 'tags': [], 'project_id': '996c75dce5a94565a0ac8977c07cc749'}}
2025-08-08 08:04:56,043 - INFO - Created VN ctest-right_vn-42943813
2025-08-08 08:04:56,057 - DEBUG - VN ctest-right_vn-42943813 UUID is f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,166 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:56,174 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:56,191 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:56,293 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,312 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,324 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:04:56,334 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:04:56,343 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3254aa45-c81e-40ed-864a-98015b7ecd1b
2025-08-08 08:04:56,353 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:56,353 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,367 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:04:56,389 - INFO - Verified VN network id 17 for VN f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,389 - INFO - Verifications in API Server for VN ctest-right_vn-42943813 passed
2025-08-08 08:04:56,390 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,403 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:04:56,414 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:04:56,424 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3254aa45-c81e-40ed-864a-98015b7ecd1b
2025-08-08 08:04:56,442 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17932100920809901549', 'uuid-lslong': '12957363981596125268'}, 'enable': 'true', 'created': '2025-08-08T08:04:55', 'last-modified': '2025-08-08T08:04:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490717'}
2025-08-08 08:04:56,449 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:56,457 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17932100920809901549', 'uuid-lslong': '12957363981596125268'}, 'enable': 'true', 'created': '2025-08-08T08:04:55', 'last-modified': '2025-08-08T08:04:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.493041'}
2025-08-08 08:04:56,466 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:56,473 - DEBUG - Control-node 10.0.0.65 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-86431188', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17932100920809901549', 'uuid-lslong': '12957363981596125268'}, 'enable': 'true', 'created': '2025-08-08T08:04:55', 'last-modified': '2025-08-08T08:04:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.519111'}
2025-08-08 08:04:56,481 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-08 08:04:56,481 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-42943813 passed
2025-08-08 08:04:56,482 - DEBUG - ====Verifying policy data for ctest-right_vn-42943813 in API_Server ======
2025-08-08 08:04:56,482 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:04:56,490 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:04:56,507 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:04:56,607 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/f8db9ec7-7554-4ded-b3d1-cde3e47a6854
2025-08-08 08:04:56,621 - DEBUG - =>VN ctest-right_vn-42943813 has no policy to be verified
2025-08-08 08:04:56,621 - DEBUG - Verifying the vn in opserver
2025-08-08 08:04:56,621 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813 virtual network link through opserver 10.0.0.50
2025-08-08 08:04:56,621 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-08 08:04:56,636 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813?flat'}
2025-08-08 08:04:56,637 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813 virtual network link through opserver 10.0.0.254
2025-08-08 08:04:56,637 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-08 08:04:56,653 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813?flat'}
2025-08-08 08:04:56,653 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813 virtual network link through opserver 10.0.0.65
2025-08-08 08:04:56,653 - DEBUG - Requesting: http://10.0.0.65:8081/analytics/uves/virtual-networks
2025-08-08 08:04:56,669 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813', 'href': 'http://10.0.0.65:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813?flat'}
2025-08-08 08:04:56,669 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-86431188:ctest-right_vn-42943813 is found in opserver
2025-08-08 08:04:56,703 - DEBUG - Do not have enough data to verify VN in agent
2025-08-08 08:04:56,718 - DEBUG - VRF ids for VN ctest-right_vn-42943813: {}
2025-08-08 08:04:57,842 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-1)
2025-08-08 08:04:59,300 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3781-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3781-2)
2025-08-08 08:04:59,301 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-33936009']
2025-08-08 08:04:59,637 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-33936009'] created succesfully
2025-08-08 08:05:00,156 - DEBUG - Image by name tiny_nat_fw not found
2025-08-08 08:05:00,156 - DEBUG - Installing image tiny_nat_fw
2025-08-08 08:05:00,156 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-08 08:05:00,156 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:05:00,156 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:05:00,156 - DEBUG - Unzip image
2025-08-08 08:05:00,156 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-08 08:05:00,178 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-08 08:05:00,178 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-33936009']
2025-08-08 08:05:00,296 - INFO - Service template: ['default-domain', 'ctest-service_template_1-33936009'] deleted successfully.
2025-08-08 08:05:00,296 - INFO - Skip interface_detach for VM ctest-right_vm-68217786 in state BUILD
2025-08-08 08:05:00,297 - INFO - Deleting VM ctest-right_vm-68217786
2025-08-08 08:05:00,297 - ERROR - VM ctest-right_vm-68217786 has failed to come up
2025-08-08 08:05:00,297 - ERROR - Nova failed to get host of the VM
2025-08-08 08:05:00,297 - ERROR - VM ctest-right_vm-68217786 has failed to come up
2025-08-08 08:05:00,297 - ERROR - Nova failed to get host of the VM
2025-08-08 08:05:00,439 - INFO - Skip interface_detach for VM ctest-left_vm-76444941 in state BUILD
2025-08-08 08:05:00,439 - INFO - Deleting VM ctest-left_vm-76444941
2025-08-08 08:05:00,439 - ERROR - VM ctest-left_vm-76444941 has failed to come up
2025-08-08 08:05:00,439 - ERROR - Nova failed to get host of the VM
2025-08-08 08:05:00,439 - ERROR - VM ctest-left_vm-76444941 has failed to come up
2025-08-08 08:05:00,439 - ERROR - Nova failed to get host of the VM
2025-08-08 08:05:00,537 - INFO - Deleting VN ctest-right_vn-42943813
2025-08-08 08:05:00,599 - DEBUG - VN f8db9ec7-7554-4ded-b3d1-cde3e47a6854 still in use: Unable to complete operation on network f8db9ec7-7554-4ded-b3d1-cde3e47a6854. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d5ab1eb7-1135-49fe-80a1-f37fbcc23b3a']
2025-08-08 08:05:00,599 - WARNING - Deleting VN ctest-right_vn-42943813 failed..Will retry
2025-08-08 08:05:02,794 - DEBUG - Response for deleting network ()
2025-08-08 08:05:02,795 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/a117da69-886a-4f55-aa39-a2b7ff5a092f
2025-08-08 08:05:02,803 - DEBUG - Response Code: 404
2025-08-08 08:05:02,804 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:05:02,816 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:05:02,836 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:05:02,934 - INFO - Validated that VN ctest-right_vn-42943813 is not found in API Server
2025-08-08 08:05:02,947 - DEBUG - VN ctest-right_vn-42943813 is not present in Agent 10.0.0.41
2025-08-08 08:05:02,962 - DEBUG - VN ctest-right_vn-42943813 is not present in Agent 10.0.0.27
2025-08-08 08:05:02,962 - INFO - Validated that VN ctest-right_vn-42943813 is not in any agent
2025-08-08 08:05:03,007 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-42943813 info
2025-08-08 08:05:03,007 - INFO - Deleting VN ctest-left_vn-61757867
2025-08-08 08:05:03,216 - DEBUG - Response for deleting network ()
2025-08-08 08:05:03,217 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/26a12cb2-7426-4e51-8f3a-4a1262d5050e
2025-08-08 08:05:03,225 - DEBUG - Response Code: 404
2025-08-08 08:05:03,225 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:05:03,249 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:05:03,267 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:05:03,385 - INFO - Validated that VN ctest-left_vn-61757867 is not found in API Server
2025-08-08 08:05:03,399 - DEBUG - VN ctest-left_vn-61757867 is not present in Agent 10.0.0.41
2025-08-08 08:05:03,414 - DEBUG - VN ctest-left_vn-61757867 is not present in Agent 10.0.0.27
2025-08-08 08:05:03,415 - INFO - Validated that VN ctest-left_vn-61757867 is not in any agent
2025-08-08 08:05:03,458 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-61757867 info
2025-08-08 08:05:03,458 - INFO - Deleting VN ctest-mgmt_vn-57576902
2025-08-08 08:05:03,645 - DEBUG - Response for deleting network ()
2025-08-08 08:05:03,645 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c6ad7c6a-f389-4a24-bb51-7247a664a001
2025-08-08 08:05:03,669 - DEBUG - Response Code: 404
2025-08-08 08:05:03,669 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2025-08-08 08:05:03,677 - DEBUG - Requesting: http://10.0.0.50:8082/domain/0a1f62a0-4b7c-4f3b-81ab-d2813942820b
2025-08-08 08:05:03,692 - DEBUG - Requesting: http://10.0.0.50:8082/project/996c75dc-e5a9-4565-a0ac-8977c07cc749
2025-08-08 08:05:03,803 - INFO - Validated that VN ctest-mgmt_vn-57576902 is not found in API Server
2025-08-08 08:05:03,820 - DEBUG - VN ctest-mgmt_vn-57576902 is not present in Agent 10.0.0.41
2025-08-08 08:05:03,838 - DEBUG - VN ctest-mgmt_vn-57576902 is not present in Agent 10.0.0.27
2025-08-08 08:05:03,838 - INFO - Validated that VN ctest-mgmt_vn-57576902 is not in any agent
2025-08-08 08:05:03,891 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-57576902 info
2025-08-08 08:05:05,193 - DEBUG - No XMPP flaps were noticed during the test
2025-08-08 08:05:05,193 - INFO -
2025-08-08 08:05:05,194 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-08-08 08:05:05,194 - INFO - --------------------------------------------------------------------------------
2025-08-08 08:05:05,954 - INFO - Deleted project: ctest-TestSvcRegr-86431188, ID : 996c75dc-e5a9-4565-a0ac-8977c07cc749