]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-2)
2025-09-22 03:02:02,797 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-59545778']
2025-09-22 03:02:03,107 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-59545778'] created succesfully
2025-09-22 03:02:03,475 - DEBUG - Image by name tiny_nat_fw not found
2025-09-22 03:02:03,475 - DEBUG - Installing image tiny_nat_fw
2025-09-22 03:02:03,475 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-22 03:02:03,475 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:03,475 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:03,490 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:03,611 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:03,611 - DEBUG - Unzip image
2025-09-22 03:02:03,611 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:03,633 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-22 03:02:03,633 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-59545778']
2025-09-22 03:02:03,719 - INFO - Service template: ['default-domain', 'ctest-service_template_1-59545778'] deleted successfully.
2025-09-22 03:02:03,720 - INFO - Skip interface_detach for VM ctest-right_vm-17244760 in state BUILD
2025-09-22 03:02:03,720 - INFO - Deleting VM ctest-right_vm-17244760
2025-09-22 03:02:03,749 - ERROR - VM ctest-right_vm-17244760 has failed to come up
2025-09-22 03:02:03,749 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:03,749 - ERROR - VM ctest-right_vm-17244760 has failed to come up
2025-09-22 03:02:03,749 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:03,815 - INFO - Skip interface_detach for VM ctest-left_vm-65228821 in state BUILD
2025-09-22 03:02:03,815 - INFO - Deleting VM ctest-left_vm-65228821
2025-09-22 03:02:03,815 - ERROR - VM ctest-left_vm-65228821 has failed to come up
2025-09-22 03:02:03,815 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:03,815 - ERROR - VM ctest-left_vm-65228821 has failed to come up
2025-09-22 03:02:03,815 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:03,873 - INFO - Deleting VN ctest-right_vn-33358215
2025-09-22 03:02:03,919 - DEBUG - VN 95a4a8cd-572b-485a-a62f-ad9c3711834b still in use: Unable to complete operation on network 95a4a8cd-572b-485a-a62f-ad9c3711834b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4c9472f7-fea8-4f1a-adcb-4d785189fb07']
2025-09-22 03:02:03,919 - WARNING - Deleting VN ctest-right_vn-33358215 failed..Will retry
2025-09-22 03:02:06,095 - DEBUG - Response for deleting network ()
2025-09-22 03:02:06,095 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ca512285-0f49-4961-be47-48c9816c8d1d
2025-09-22 03:02:06,104 - DEBUG - Response Code: 404
2025-09-22 03:02:06,104 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:06,114 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:06,185 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:06,296 - INFO - Validated that VN ctest-right_vn-33358215 is not found in API Server
2025-09-22 03:02:06,310 - DEBUG - VN ctest-right_vn-33358215 is not present in Agent 10.0.0.33
2025-09-22 03:02:06,324 - DEBUG - VN ctest-right_vn-33358215 is not present in Agent 10.0.0.23
2025-09-22 03:02:06,324 - INFO - Validated that VN ctest-right_vn-33358215 is not in any agent
2025-09-22 03:02:06,354 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-33358215 info
2025-09-22 03:02:06,354 - INFO - Deleting VN ctest-left_vn-07546283
2025-09-22 03:02:06,526 - DEBUG - Response for deleting network ()
2025-09-22 03:02:06,526 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/4e8011ae-bb54-4682-9b63-0730bdef4536
2025-09-22 03:02:06,535 - DEBUG - Response Code: 404
2025-09-22 03:02:06,535 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:06,543 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:06,596 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:06,680 - INFO - Validated that VN ctest-left_vn-07546283 is not found in API Server
2025-09-22 03:02:06,690 - DEBUG - VN ctest-left_vn-07546283 is not present in Agent 10.0.0.33
2025-09-22 03:02:06,699 - DEBUG - VN ctest-left_vn-07546283 is not present in Agent 10.0.0.23
2025-09-22 03:02:06,699 - INFO - Validated that VN ctest-left_vn-07546283 is not in any agent
2025-09-22 03:02:06,727 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-07546283 info
2025-09-22 03:02:06,727 - INFO - Deleting VN ctest-mgmt_vn-71330633
2025-09-22 03:02:06,876 - DEBUG - Response for deleting network ()
2025-09-22 03:02:06,876 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cff5736e-61f6-41d9-83e4-45a7a1782b49
2025-09-22 03:02:06,881 - DEBUG - Response Code: 404
2025-09-22 03:02:06,882 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:06,899 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:06,909 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:07,013 - INFO - Validated that VN ctest-mgmt_vn-71330633 is not found in API Server
2025-09-22 03:02:07,025 - DEBUG - VN ctest-mgmt_vn-71330633 is not present in Agent 10.0.0.33
2025-09-22 03:02:07,035 - DEBUG - VN ctest-mgmt_vn-71330633 is not present in Agent 10.0.0.23
2025-09-22 03:02:07,035 - INFO - Validated that VN ctest-mgmt_vn-71330633 is not in any agent
2025-09-22 03:02:07,071 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-71330633 info
2025-09-22 03:02:07,071 - INFO - Deleting VM ctest-vm-in-third-vn-30303183
2025-09-22 03:02:07,130 - INFO - Deleting VN ctest-third-vn-62544000
2025-09-22 03:02:07,169 - DEBUG - VN 1f61e685-38fa-4034-8682-ea77f11f7c04 still in use: Unable to complete operation on network 1f61e685-38fa-4034-8682-ea77f11f7c04. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7b065194-425e-4b45-ae49-296852509b57']
2025-09-22 03:02:07,169 - WARNING - Deleting VN ctest-third-vn-62544000 failed..Will retry
2025-09-22 03:02:09,338 - DEBUG - Response for deleting network ()
2025-09-22 03:02:10,646 - DEBUG - No XMPP flaps were noticed during the test
2025-09-22 03:02:10,646 - INFO -
2025-09-22 03:02:10,646 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-09-22 03:02:10,646 - INFO - --------------------------------------------------------------------------------
2025-09-22 03:02:10,648 - INFO - ================================================================================
2025-09-22 03:02:10,649 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-09-22 03:02:11,932 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.193': '0'}} with
2025-09-22 03:02:11,932 - INFO - Initial checks done. Running the testcase now
2025-09-22 03:02:11,932 - INFO -
2025-09-22 03:02:12,190 - DEBUG - Response for create_network : {'network': {'id': '771af4ad-f81c-45c4-b52a-7fc811b95c60', 'name': 'ctest-mgmt_vn-27564920', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'project_id': '40c5dc34354b4525ad5755f56987b128', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-66884561', 'ctest-mgmt_vn-27564920'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:12,417 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fa0271d0-5ed2-4a71-a142-7ee50529dbf2', 'name': '', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'network_id': '771af4ad-f81c-45c4-b52a-7fc811b95c60', 'ip_version': 4, 'cidr': '102.232.174.192/26', 'allocation_pools': [{'start': '102.232.174.194', 'end': '102.232.174.254'}], 'gateway_ip': '102.232.174.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.232.174.194', 'tags': [], 'project_id': '40c5dc34354b4525ad5755f56987b128'}}
2025-09-22 03:02:12,443 - INFO - Created VN ctest-mgmt_vn-27564920
2025-09-22 03:02:12,459 - DEBUG - VN ctest-mgmt_vn-27564920 UUID is 771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,524 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:12,530 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:12,543 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:12,630 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,642 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,650 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:12,656 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:12,662 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5691cde8-bcd1-4eb4-8c8b-812ce62c3173
2025-09-22 03:02:12,667 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:12,667 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,675 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:12,691 - INFO - Verified VN network id 11 for VN 771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,691 - INFO - Verifications in API Server for VN ctest-mgmt_vn-27564920 passed
2025-09-22 03:02:12,691 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,700 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:12,706 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:12,713 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/5691cde8-bcd1-4eb4-8c8b-812ce62c3173
2025-09-22 03:02:12,725 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8582441067937023428', 'uuid-lslong': '13054386967154351200'}, 'enable': 'true', 'created': '2025-09-22T03:02:12', 'last-modified': '2025-09-22T03:02:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372900'}
2025-09-22 03:02:12,732 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:12,737 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8582441067937023428', 'uuid-lslong': '13054386967154351200'}, 'enable': 'true', 'created': '2025-09-22T03:02:12', 'last-modified': '2025-09-22T03:02:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383707'}
2025-09-22 03:02:12,744 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:12,750 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8582441067937023428', 'uuid-lslong': '13054386967154351200'}, 'enable': 'true', 'created': '2025-09-22T03:02:12', 'last-modified': '2025-09-22T03:02:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395740'}
2025-09-22 03:02:12,757 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:12,757 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-27564920 passed
2025-09-22 03:02:12,757 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-27564920 in API_Server ======
2025-09-22 03:02:12,757 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:12,763 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:12,774 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:12,867 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/771af4ad-f81c-45c4-b52a-7fc811b95c60
2025-09-22 03:02:12,883 - DEBUG - =>VN ctest-mgmt_vn-27564920 has no policy to be verified
2025-09-22 03:02:12,883 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:12,883 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:12,883 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:12,901 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920?flat'}
2025-09-22 03:02:12,901 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:12,901 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:12,916 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920?flat'}
2025-09-22 03:02:12,916 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:12,916 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:12,928 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920?flat'}
2025-09-22 03:02:12,928 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-66884561:ctest-mgmt_vn-27564920 is found in opserver
2025-09-22 03:02:12,947 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:12,958 - DEBUG - VRF ids for VN ctest-mgmt_vn-27564920: {}
2025-09-22 03:02:13,236 - DEBUG - Response for create_network : {'network': {'id': '3e3b6b86-9f05-4196-9fc6-dc966cddcf66', 'name': 'ctest-left_vn-89088845', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'project_id': '40c5dc34354b4525ad5755f56987b128', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-66884561', 'ctest-left_vn-89088845'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:13,471 - DEBUG - Response for create_subnet : {'subnet': {'id': '45a6d8ce-6dde-43ac-86b5-9e942367fe16', 'name': '', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'network_id': '3e3b6b86-9f05-4196-9fc6-dc966cddcf66', 'ip_version': 4, 'cidr': '87.238.146.0/26', 'allocation_pools': [{'start': '87.238.146.2', 'end': '87.238.146.62'}], 'gateway_ip': '87.238.146.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.238.146.2', 'tags': [], 'project_id': '40c5dc34354b4525ad5755f56987b128'}}
2025-09-22 03:02:13,492 - INFO - Created VN ctest-left_vn-89088845
2025-09-22 03:02:13,504 - DEBUG - VN ctest-left_vn-89088845 UUID is 3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,573 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:13,579 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:13,589 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:13,690 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,704 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,714 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:13,723 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:13,731 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b24ac702-942a-4f86-a305-aaa833f0e314
2025-09-22 03:02:13,739 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:13,739 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,750 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:13,766 - INFO - Verified VN network id 12 for VN 3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,766 - INFO - Verifications in API Server for VN ctest-left_vn-89088845 passed
2025-09-22 03:02:13,766 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,776 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:13,784 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:13,792 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b24ac702-942a-4f86-a305-aaa833f0e314
2025-09-22 03:02:13,802 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4484296079915172246', 'uuid-lslong': '11513132036048932710'}, 'enable': 'true', 'created': '2025-09-22T03:02:13', 'last-modified': '2025-09-22T03:02:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396805'}
2025-09-22 03:02:13,806 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:13,811 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4484296079915172246', 'uuid-lslong': '11513132036048932710'}, 'enable': 'true', 'created': '2025-09-22T03:02:13', 'last-modified': '2025-09-22T03:02:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407531'}
2025-09-22 03:02:13,818 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:13,822 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4484296079915172246', 'uuid-lslong': '11513132036048932710'}, 'enable': 'true', 'created': '2025-09-22T03:02:13', 'last-modified': '2025-09-22T03:02:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419309'}
2025-09-22 03:02:13,828 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:13,828 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-89088845 passed
2025-09-22 03:02:13,828 - DEBUG - ====Verifying policy data for ctest-left_vn-89088845 in API_Server ======
2025-09-22 03:02:13,828 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:13,834 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:13,844 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:13,937 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/3e3b6b86-9f05-4196-9fc6-dc966cddcf66
2025-09-22 03:02:13,946 - DEBUG - =>VN ctest-left_vn-89088845 has no policy to be verified
2025-09-22 03:02:13,946 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:13,946 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:13,946 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:13,957 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845?flat'}
2025-09-22 03:02:13,957 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:13,957 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:13,970 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845?flat'}
2025-09-22 03:02:13,970 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:13,970 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:13,990 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845?flat'}
2025-09-22 03:02:13,990 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-66884561:ctest-left_vn-89088845 is found in opserver
2025-09-22 03:02:14,010 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:14,018 - DEBUG - VRF ids for VN ctest-left_vn-89088845: {}
2025-09-22 03:02:14,328 - DEBUG - Response for create_network : {'network': {'id': '946e2ef7-0cb6-4e45-b618-1fdea9836fcd', 'name': 'ctest-right_vn-67706980', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'project_id': '40c5dc34354b4525ad5755f56987b128', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-66884561', 'ctest-right_vn-67706980'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:14,575 - DEBUG - Response for create_subnet : {'subnet': {'id': '871fb6bc-33f8-4a4c-9ad8-8f6f8b3adac4', 'name': '', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'network_id': '946e2ef7-0cb6-4e45-b618-1fdea9836fcd', 'ip_version': 4, 'cidr': '87.123.65.0/26', 'allocation_pools': [{'start': '87.123.65.2', 'end': '87.123.65.62'}], 'gateway_ip': '87.123.65.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.123.65.2', 'tags': [], 'project_id': '40c5dc34354b4525ad5755f56987b128'}}
2025-09-22 03:02:14,593 - INFO - Created VN ctest-right_vn-67706980
2025-09-22 03:02:14,605 - DEBUG - VN ctest-right_vn-67706980 UUID is 946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,673 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:14,678 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:14,687 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:14,775 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,791 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,800 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:14,807 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:14,813 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9cbc29e4-8a0b-4403-a380-1fdd130f4ef6
2025-09-22 03:02:14,819 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:14,819 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,827 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:14,844 - INFO - Verified VN network id 13 for VN 946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,844 - INFO - Verifications in API Server for VN ctest-right_vn-67706980 passed
2025-09-22 03:02:14,844 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:14,852 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:14,858 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:14,864 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9cbc29e4-8a0b-4403-a380-1fdd130f4ef6
2025-09-22 03:02:14,874 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10695537803656580677', 'uuid-lslong': '13121272555531104205'}, 'enable': 'true', 'created': '2025-09-22T03:02:14', 'last-modified': '2025-09-22T03:02:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.347045'}
2025-09-22 03:02:14,881 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:14,887 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10695537803656580677', 'uuid-lslong': '13121272555531104205'}, 'enable': 'true', 'created': '2025-09-22T03:02:14', 'last-modified': '2025-09-22T03:02:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362556'}
2025-09-22 03:02:14,893 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:14,898 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10695537803656580677', 'uuid-lslong': '13121272555531104205'}, 'enable': 'true', 'created': '2025-09-22T03:02:14', 'last-modified': '2025-09-22T03:02:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.371931'}
2025-09-22 03:02:14,904 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:14,904 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-67706980 passed
2025-09-22 03:02:14,904 - DEBUG - ====Verifying policy data for ctest-right_vn-67706980 in API_Server ======
2025-09-22 03:02:14,904 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:14,911 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:14,922 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:15,016 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/946e2ef7-0cb6-4e45-b618-1fdea9836fcd
2025-09-22 03:02:15,026 - DEBUG - =>VN ctest-right_vn-67706980 has no policy to be verified
2025-09-22 03:02:15,026 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:15,026 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:15,026 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:15,042 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980?flat'}
2025-09-22 03:02:15,042 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:15,042 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:15,056 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980?flat'}
2025-09-22 03:02:15,056 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:15,056 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:15,069 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980?flat'}
2025-09-22 03:02:15,069 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-66884561:ctest-right_vn-67706980 is found in opserver
2025-09-22 03:02:15,088 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:15,096 - DEBUG - VRF ids for VN ctest-right_vn-67706980: {}
2025-09-22 03:02:15,395 - DEBUG - Response for create_network : {'network': {'id': 'e8913734-5606-4868-9a34-15f66f7b1a6c', 'name': 'ctest-trans_left_vn-46971766', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'project_id': '40c5dc34354b4525ad5755f56987b128', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-66884561', 'ctest-trans_left_vn-46971766'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:15,675 - DEBUG - Response for create_subnet : {'subnet': {'id': '7e8f78df-23ac-43d2-8d24-37ebb9c322fb', 'name': '', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'network_id': 'e8913734-5606-4868-9a34-15f66f7b1a6c', 'ip_version': 4, 'cidr': '19.245.8.64/26', 'allocation_pools': [{'start': '19.245.8.66', 'end': '19.245.8.126'}], 'gateway_ip': '19.245.8.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '19.245.8.66', 'tags': [], 'project_id': '40c5dc34354b4525ad5755f56987b128'}}
2025-09-22 03:02:15,699 - INFO - Created VN ctest-trans_left_vn-46971766
2025-09-22 03:02:15,712 - DEBUG - VN ctest-trans_left_vn-46971766 UUID is e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,782 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:15,787 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:15,799 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:15,882 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,908 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,918 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:15,925 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:15,933 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/90af01e7-c3b3-4d69-8da8-1bb39fb5bca2
2025-09-22 03:02:15,940 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-22 03:02:15,940 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,949 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:15,965 - INFO - Verified VN network id 14 for VN e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,965 - INFO - Verifications in API Server for VN ctest-trans_left_vn-46971766 passed
2025-09-22 03:02:15,965 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:15,974 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:15,982 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:15,988 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/90af01e7-c3b3-4d69-8da8-1bb39fb5bca2
2025-09-22 03:02:16,007 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16758236386343405672', 'uuid-lslong': '11111530328806333036'}, 'enable': 'true', 'created': '2025-09-22T03:02:15', 'last-modified': '2025-09-22T03:02:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407088'}
2025-09-22 03:02:16,017 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-22 03:02:16,023 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16758236386343405672', 'uuid-lslong': '11111530328806333036'}, 'enable': 'true', 'created': '2025-09-22T03:02:15', 'last-modified': '2025-09-22T03:02:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427985'}
2025-09-22 03:02:16,028 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-22 03:02:16,034 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16758236386343405672', 'uuid-lslong': '11111530328806333036'}, 'enable': 'true', 'created': '2025-09-22T03:02:15', 'last-modified': '2025-09-22T03:02:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440023'}
2025-09-22 03:02:16,040 - DEBUG - Route Targets: ['target:64512:8000007']
2025-09-22 03:02:16,040 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-46971766 passed
2025-09-22 03:02:16,040 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-46971766 in API_Server ======
2025-09-22 03:02:16,040 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:16,046 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:16,057 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:16,173 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e8913734-5606-4868-9a34-15f66f7b1a6c
2025-09-22 03:02:16,185 - DEBUG - =>VN ctest-trans_left_vn-46971766 has no policy to be verified
2025-09-22 03:02:16,185 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:16,185 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:16,185 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:16,198 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766?flat'}
2025-09-22 03:02:16,198 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:16,198 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:16,212 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766?flat'}
2025-09-22 03:02:16,212 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:16,212 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:16,226 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766?flat'}
2025-09-22 03:02:16,227 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-66884561:ctest-trans_left_vn-46971766 is found in opserver
2025-09-22 03:02:16,248 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:16,258 - DEBUG - VRF ids for VN ctest-trans_left_vn-46971766: {}
2025-09-22 03:02:16,552 - DEBUG - Response for create_network : {'network': {'id': 'ee0544c2-7f0d-4900-afd0-f2d383d9e06d', 'name': 'ctest-trans_right_vn-80560810', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'project_id': '40c5dc34354b4525ad5755f56987b128', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-66884561', 'ctest-trans_right_vn-80560810'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:16,825 - DEBUG - Response for create_subnet : {'subnet': {'id': '515abecb-957a-447e-87db-796223580f9e', 'name': '', 'tenant_id': '40c5dc34354b4525ad5755f56987b128', 'network_id': 'ee0544c2-7f0d-4900-afd0-f2d383d9e06d', 'ip_version': 4, 'cidr': '193.135.87.128/26', 'allocation_pools': [{'start': '193.135.87.130', 'end': '193.135.87.190'}], 'gateway_ip': '193.135.87.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.135.87.130', 'tags': [], 'project_id': '40c5dc34354b4525ad5755f56987b128'}}
2025-09-22 03:02:16,849 - INFO - Created VN ctest-trans_right_vn-80560810
2025-09-22 03:02:16,860 - DEBUG - VN ctest-trans_right_vn-80560810 UUID is ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:16,931 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:16,936 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:16,946 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:17,034 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,046 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,055 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:17,065 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:17,072 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/e79f4eae-921e-4131-9434-a87eeeec2efa
2025-09-22 03:02:17,079 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-22 03:02:17,079 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,088 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:17,103 - INFO - Verified VN network id 15 for VN ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,103 - INFO - Verifications in API Server for VN ctest-trans_right_vn-80560810 passed
2025-09-22 03:02:17,103 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,113 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:17,121 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:17,128 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/e79f4eae-921e-4131-9434-a87eeeec2efa
2025-09-22 03:02:17,141 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17151190358056323328', 'uuid-lslong': '12668892742057320557'}, 'enable': 'true', 'created': '2025-09-22T03:02:16', 'last-modified': '2025-09-22T03:02:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.366054'}
2025-09-22 03:02:17,150 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-22 03:02:17,157 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17151190358056323328', 'uuid-lslong': '12668892742057320557'}, 'enable': 'true', 'created': '2025-09-22T03:02:16', 'last-modified': '2025-09-22T03:02:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377753'}
2025-09-22 03:02:17,164 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-22 03:02:17,169 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-66884561', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17151190358056323328', 'uuid-lslong': '12668892742057320557'}, 'enable': 'true', 'created': '2025-09-22T03:02:16', 'last-modified': '2025-09-22T03:02:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389403'}
2025-09-22 03:02:17,174 - DEBUG - Route Targets: ['target:64512:8000008']
2025-09-22 03:02:17,174 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-80560810 passed
2025-09-22 03:02:17,174 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-80560810 in API_Server ======
2025-09-22 03:02:17,174 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:17,180 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:17,190 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:17,285 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/ee0544c2-7f0d-4900-afd0-f2d383d9e06d
2025-09-22 03:02:17,296 - DEBUG - =>VN ctest-trans_right_vn-80560810 has no policy to be verified
2025-09-22 03:02:17,296 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:17,296 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:17,296 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:17,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810?flat'}
2025-09-22 03:02:17,308 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:17,309 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:17,325 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810?flat'}
2025-09-22 03:02:17,325 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:17,325 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:17,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810?flat'}
2025-09-22 03:02:17,341 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-66884561:ctest-trans_right_vn-80560810 is found in opserver
2025-09-22 03:02:17,365 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:17,377 - DEBUG - VRF ids for VN ctest-trans_right_vn-80560810: {}
2025-09-22 03:02:18,421 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-2)
2025-09-22 03:02:19,886 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-1)
2025-09-22 03:02:19,886 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-57428544']
2025-09-22 03:02:20,282 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-57428544'] created succesfully
2025-09-22 03:02:20,698 - DEBUG - Image by name tiny_trans_fw not found
2025-09-22 03:02:20,698 - DEBUG - Installing image tiny_trans_fw
2025-09-22 03:02:20,698 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-09-22 03:02:20,699 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-22 03:02:20,699 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-09-22 03:02:20,715 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-22 03:02:20,743 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-22 03:02:20,743 - DEBUG - Unzip image
2025-09-22 03:02:20,743 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-09-22 03:02:20,758 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-09-22 03:02:20,758 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-57428544']
2025-09-22 03:02:20,869 - INFO - Service template: ['default-domain', 'ctest-service_template_1-57428544'] deleted successfully.
2025-09-22 03:02:20,869 - INFO - Skip interface_detach for VM ctest-right_vm-72150264 in state BUILD
2025-09-22 03:02:20,869 - INFO - Deleting VM ctest-right_vm-72150264
2025-09-22 03:02:20,869 - ERROR - VM ctest-right_vm-72150264 has failed to come up
2025-09-22 03:02:20,869 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:20,869 - ERROR - VM ctest-right_vm-72150264 has failed to come up
2025-09-22 03:02:20,869 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:20,965 - INFO - Skip interface_detach for VM ctest-left_vm-05489007 in state BUILD
2025-09-22 03:02:20,965 - INFO - Deleting VM ctest-left_vm-05489007
2025-09-22 03:02:20,965 - ERROR - VM ctest-left_vm-05489007 has failed to come up
2025-09-22 03:02:20,965 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:20,965 - ERROR - VM ctest-left_vm-05489007 has failed to come up
2025-09-22 03:02:20,965 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:21,034 - INFO - Deleting VN ctest-trans_right_vn-80560810
2025-09-22 03:02:21,197 - DEBUG - Response for deleting network ()
2025-09-22 03:02:21,197 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/56890a22-9e63-4dcb-8ad2-865ce549db46
2025-09-22 03:02:21,204 - DEBUG - Response Code: 404
2025-09-22 03:02:21,204 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:21,210 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:21,224 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:21,344 - INFO - Validated that VN ctest-trans_right_vn-80560810 is not found in API Server
2025-09-22 03:02:21,364 - DEBUG - VN ctest-trans_right_vn-80560810 is not present in Agent 10.0.0.33
2025-09-22 03:02:21,375 - DEBUG - VN ctest-trans_right_vn-80560810 is not present in Agent 10.0.0.23
2025-09-22 03:02:21,375 - INFO - Validated that VN ctest-trans_right_vn-80560810 is not in any agent
2025-09-22 03:02:21,408 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-80560810 info
2025-09-22 03:02:21,408 - INFO - Deleting VN ctest-trans_left_vn-46971766
2025-09-22 03:02:21,566 - DEBUG - Response for deleting network ()
2025-09-22 03:02:21,566 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/f68a9c2f-b65d-4440-a75f-f0272148f1a8
2025-09-22 03:02:21,572 - DEBUG - Response Code: 404
2025-09-22 03:02:21,572 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:21,577 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:21,588 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:21,688 - INFO - Validated that VN ctest-trans_left_vn-46971766 is not found in API Server
2025-09-22 03:02:21,709 - DEBUG - VN ctest-trans_left_vn-46971766 is not present in Agent 10.0.0.33
2025-09-22 03:02:21,717 - DEBUG - VN ctest-trans_left_vn-46971766 is not present in Agent 10.0.0.23
2025-09-22 03:02:21,717 - INFO - Validated that VN ctest-trans_left_vn-46971766 is not in any agent
2025-09-22 03:02:21,765 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-46971766 info
2025-09-22 03:02:21,765 - INFO - Deleting VN ctest-right_vn-67706980
2025-09-22 03:02:21,813 - DEBUG - VN 946e2ef7-0cb6-4e45-b618-1fdea9836fcd still in use: Unable to complete operation on network 946e2ef7-0cb6-4e45-b618-1fdea9836fcd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cd67f630-3304-402c-a3cb-00a50ca82643']
2025-09-22 03:02:21,813 - WARNING - Deleting VN ctest-right_vn-67706980 failed..Will retry
2025-09-22 03:02:23,960 - DEBUG - Response for deleting network ()
2025-09-22 03:02:23,960 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/099ff73d-0989-481a-9793-7216576eba3e
2025-09-22 03:02:23,965 - DEBUG - Response Code: 404
2025-09-22 03:02:23,965 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:23,971 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:23,994 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:24,080 - INFO - Validated that VN ctest-right_vn-67706980 is not found in API Server
2025-09-22 03:02:24,091 - DEBUG - VN ctest-right_vn-67706980 is not present in Agent 10.0.0.33
2025-09-22 03:02:24,099 - DEBUG - VN ctest-right_vn-67706980 is not present in Agent 10.0.0.23
2025-09-22 03:02:24,100 - INFO - Validated that VN ctest-right_vn-67706980 is not in any agent
2025-09-22 03:02:24,127 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-67706980 info
2025-09-22 03:02:24,127 - INFO - Deleting VN ctest-left_vn-89088845
2025-09-22 03:02:24,290 - DEBUG - Response for deleting network ()
2025-09-22 03:02:24,290 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/10bddc21-cfad-4153-8253-1f5cf5d6e30e
2025-09-22 03:02:24,312 - DEBUG - Response Code: 404
2025-09-22 03:02:24,312 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:24,319 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:24,334 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:24,439 - INFO - Validated that VN ctest-left_vn-89088845 is not found in API Server
2025-09-22 03:02:24,450 - DEBUG - VN ctest-left_vn-89088845 is not present in Agent 10.0.0.33
2025-09-22 03:02:24,460 - DEBUG - VN ctest-left_vn-89088845 is not present in Agent 10.0.0.23
2025-09-22 03:02:24,460 - INFO - Validated that VN ctest-left_vn-89088845 is not in any agent
2025-09-22 03:02:24,498 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-89088845 info
2025-09-22 03:02:24,498 - INFO - Deleting VN ctest-mgmt_vn-27564920
2025-09-22 03:02:24,688 - DEBUG - Response for deleting network ()
2025-09-22 03:02:24,688 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/3a97346e-f98e-485a-bdfb-e22f8650ae3e
2025-09-22 03:02:24,693 - DEBUG - Response Code: 404
2025-09-22 03:02:24,693 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:24,705 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:24,722 - DEBUG - Requesting: http://10.0.0.56:8082/project/40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:24,810 - INFO - Validated that VN ctest-mgmt_vn-27564920 is not found in API Server
2025-09-22 03:02:24,824 - DEBUG - VN ctest-mgmt_vn-27564920 is not present in Agent 10.0.0.33
2025-09-22 03:02:24,832 - DEBUG - VN ctest-mgmt_vn-27564920 is not present in Agent 10.0.0.23
2025-09-22 03:02:24,832 - INFO - Validated that VN ctest-mgmt_vn-27564920 is not in any agent
2025-09-22 03:02:24,870 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-27564920 info
2025-09-22 03:02:26,141 - DEBUG - No XMPP flaps were noticed during the test
2025-09-22 03:02:26,141 - INFO -
2025-09-22 03:02:26,141 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-09-22 03:02:26,141 - INFO - --------------------------------------------------------------------------------
2025-09-22 03:02:26,895 - INFO - Deleted project: ctest-TestSvcRegr-66884561, ID : 40c5dc34-354b-4525-ad57-55f56987b128
2025-09-22 03:02:27,048 - INFO - Domain Default found not creating
2025-09-22 03:02:27,190 - INFO - Project ctest-TestSvcRegr-38628394 not found, creating it
2025-09-22 03:02:27,709 - INFO - Created Project:ctest-TestSvcRegr-38628394, ID : 0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:29,064 - INFO - ================================================================================
2025-09-22 03:02:29,064 - INFO - STARTING TEST : test_svc_CEM_22032
2025-09-22 03:02:30,356 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.193': '0'}} with
2025-09-22 03:02:30,357 - INFO - Initial checks done. Running the testcase now
2025-09-22 03:02:30,357 - INFO -
2025-09-22 03:02:31,040 - DEBUG - Response for create_network : {'network': {'id': '6cd5402a-ec9a-4a1f-a111-d61fd4c48be5', 'name': 'ctest-mgmt_vn-96492422', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'ctest-mgmt_vn-96492422'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:31,468 - DEBUG - Response for create_subnet : {'subnet': {'id': '6dba5f34-c3ab-4770-ae9d-3f97a486c3d9', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': '6cd5402a-ec9a-4a1f-a111-d61fd4c48be5', 'ip_version': 4, 'cidr': '5.240.20.192/26', 'allocation_pools': [{'start': '5.240.20.194', 'end': '5.240.20.254'}], 'gateway_ip': '5.240.20.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '5.240.20.194', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:31,490 - INFO - Created VN ctest-mgmt_vn-96492422
2025-09-22 03:02:31,502 - DEBUG - VN ctest-mgmt_vn-96492422 UUID is 6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:31,579 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:31,911 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:31,952 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:31,976 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:32,065 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,091 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,101 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:32,110 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:32,118 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/284a1f31-24ad-43f1-b932-cab8caef0024
2025-09-22 03:02:32,128 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:32,128 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,136 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:32,156 - INFO - Verified VN network id 11 for VN 6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,156 - INFO - Verifications in API Server for VN ctest-mgmt_vn-96492422 passed
2025-09-22 03:02:32,156 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,165 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:32,171 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:32,177 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/284a1f31-24ad-43f1-b932-cab8caef0024
2025-09-22 03:02:32,187 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7842244879237925407', 'uuid-lslong': '11606293146912459749'}, 'enable': 'true', 'created': '2025-09-22T03:02:30', 'last-modified': '2025-09-22T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.777243'}
2025-09-22 03:02:32,192 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:32,198 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7842244879237925407', 'uuid-lslong': '11606293146912459749'}, 'enable': 'true', 'created': '2025-09-22T03:02:30', 'last-modified': '2025-09-22T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.773605'}
2025-09-22 03:02:32,204 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:32,209 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7842244879237925407', 'uuid-lslong': '11606293146912459749'}, 'enable': 'true', 'created': '2025-09-22T03:02:30', 'last-modified': '2025-09-22T03:02:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.773419'}
2025-09-22 03:02:32,214 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:32,215 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-96492422 passed
2025-09-22 03:02:32,215 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-96492422 in API_Server ======
2025-09-22 03:02:32,215 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:32,220 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:32,230 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:32,334 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cd5402a-ec9a-4a1f-a111-d61fd4c48be5
2025-09-22 03:02:32,345 - DEBUG - =>VN ctest-mgmt_vn-96492422 has no policy to be verified
2025-09-22 03:02:32,345 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:32,345 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:32,345 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:32,644 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:32,700 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422?flat'}
2025-09-22 03:02:32,700 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:32,701 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:32,991 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:33,052 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422?flat'}
2025-09-22 03:02:33,052 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:33,052 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:33,347 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:33,402 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422?flat'}
2025-09-22 03:02:33,402 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-96492422 is found in opserver
2025-09-22 03:02:33,422 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:33,431 - DEBUG - VRF ids for VN ctest-mgmt_vn-96492422: {}
2025-09-22 03:02:33,717 - DEBUG - Response for create_network : {'network': {'id': '5cfbaf20-182d-452a-9951-c2f742818e99', 'name': 'service-left-vn-48125310', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'service-left-vn-48125310'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:33,946 - DEBUG - Response for create_subnet : {'subnet': {'id': '63e2a5a7-536c-4d67-b56f-5a1c56590404', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': '5cfbaf20-182d-452a-9951-c2f742818e99', 'ip_version': 4, 'cidr': '220.195.29.192/26', 'allocation_pools': [{'start': '220.195.29.194', 'end': '220.195.29.254'}], 'gateway_ip': '220.195.29.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '220.195.29.194', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:33,971 - INFO - Created VN service-left-vn-48125310
2025-09-22 03:02:33,985 - DEBUG - VN service-left-vn-48125310 UUID is 5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,052 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:34,059 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:34,070 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:34,177 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,189 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,197 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:34,204 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:34,210 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/40c89600-f5d7-4c57-8142-5d0e1f0b9505
2025-09-22 03:02:34,216 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:34,216 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,224 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:34,239 - INFO - Verified VN network id 12 for VN 5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,239 - INFO - Verifications in API Server for VN service-left-vn-48125310 passed
2025-09-22 03:02:34,239 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,247 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:34,253 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:34,260 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/40c89600-f5d7-4c57-8142-5d0e1f0b9505
2025-09-22 03:02:34,271 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6700141423023179050', 'uuid-lslong': '11047825728145034905'}, 'enable': 'true', 'created': '2025-09-22T03:02:33', 'last-modified': '2025-09-22T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370471'}
2025-09-22 03:02:34,276 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:34,281 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6700141423023179050', 'uuid-lslong': '11047825728145034905'}, 'enable': 'true', 'created': '2025-09-22T03:02:33', 'last-modified': '2025-09-22T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383625'}
2025-09-22 03:02:34,287 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:34,293 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6700141423023179050', 'uuid-lslong': '11047825728145034905'}, 'enable': 'true', 'created': '2025-09-22T03:02:33', 'last-modified': '2025-09-22T03:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392121'}
2025-09-22 03:02:34,298 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:34,298 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-48125310 passed
2025-09-22 03:02:34,298 - DEBUG - ====Verifying policy data for service-left-vn-48125310 in API_Server ======
2025-09-22 03:02:34,298 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:34,304 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:34,314 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:34,415 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5cfbaf20-182d-452a-9951-c2f742818e99
2025-09-22 03:02:34,434 - DEBUG - =>VN service-left-vn-48125310 has no policy to be verified
2025-09-22 03:02:34,434 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:34,434 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:34,434 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:34,510 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310?flat'}
2025-09-22 03:02:34,511 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:34,511 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:34,585 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310?flat'}
2025-09-22 03:02:34,585 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:34,585 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:34,641 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310?flat'}
2025-09-22 03:02:34,641 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:service-left-vn-48125310 is found in opserver
2025-09-22 03:02:34,664 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:34,686 - DEBUG - VRF ids for VN service-left-vn-48125310: {}
2025-09-22 03:02:34,961 - DEBUG - Response for create_network : {'network': {'id': '0e29688a-f466-4cc9-b7ea-6200bd3fb62c', 'name': 'service-right-vn-49178236', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'service-right-vn-49178236'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:35,258 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a9f90eb-1e66-4b92-a1be-ef1dfde9fc60', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': '0e29688a-f466-4cc9-b7ea-6200bd3fb62c', 'ip_version': 4, 'cidr': '208.113.25.64/26', 'allocation_pools': [{'start': '208.113.25.66', 'end': '208.113.25.126'}], 'gateway_ip': '208.113.25.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '208.113.25.66', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:35,281 - INFO - Created VN service-right-vn-49178236
2025-09-22 03:02:35,293 - DEBUG - VN service-right-vn-49178236 UUID is 0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,390 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:35,396 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:35,406 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:35,525 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,543 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,553 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:35,566 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:35,574 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8e8d68ad-bd0a-4c0a-a095-d2d5f9e832d8
2025-09-22 03:02:35,582 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:35,582 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,592 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:35,618 - INFO - Verified VN network id 13 for VN 0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,618 - INFO - Verifications in API Server for VN service-right-vn-49178236 passed
2025-09-22 03:02:35,618 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,629 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:35,638 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:35,645 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8e8d68ad-bd0a-4c0a-a095-d2d5f9e832d8
2025-09-22 03:02:35,656 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1020461736591248585', 'uuid-lslong': '13252512608805697068'}, 'enable': 'true', 'created': '2025-09-22T03:02:34', 'last-modified': '2025-09-22T03:02:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470351'}
2025-09-22 03:02:35,661 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:35,666 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1020461736591248585', 'uuid-lslong': '13252512608805697068'}, 'enable': 'true', 'created': '2025-09-22T03:02:34', 'last-modified': '2025-09-22T03:02:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.480362'}
2025-09-22 03:02:35,672 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:35,677 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1020461736591248585', 'uuid-lslong': '13252512608805697068'}, 'enable': 'true', 'created': '2025-09-22T03:02:34', 'last-modified': '2025-09-22T03:02:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.491902'}
2025-09-22 03:02:35,683 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:35,683 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-49178236 passed
2025-09-22 03:02:35,683 - DEBUG - ====Verifying policy data for service-right-vn-49178236 in API_Server ======
2025-09-22 03:02:35,683 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:35,690 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:35,699 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:35,805 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0e29688a-f466-4cc9-b7ea-6200bd3fb62c
2025-09-22 03:02:35,814 - DEBUG - =>VN service-right-vn-49178236 has no policy to be verified
2025-09-22 03:02:35,814 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:35,814 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:35,814 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:35,862 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236?flat'}
2025-09-22 03:02:35,862 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:35,862 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:35,913 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236?flat'}
2025-09-22 03:02:35,913 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:35,913 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:35,960 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236?flat'}
2025-09-22 03:02:35,960 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:service-right-vn-49178236 is found in opserver
2025-09-22 03:02:35,980 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:35,990 - DEBUG - VRF ids for VN service-right-vn-49178236: {}
2025-09-22 03:02:36,056 - DEBUG - Services list from nova: [, , , ]
2025-09-22 03:02:37,094 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-2)
2025-09-22 03:02:38,383 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-1)
2025-09-22 03:02:38,383 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-61126231']
2025-09-22 03:02:38,689 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-61126231'] created succesfully
2025-09-22 03:02:39,069 - DEBUG - Image by name tiny_nat_fw not found
2025-09-22 03:02:39,070 - DEBUG - Installing image tiny_nat_fw
2025-09-22 03:02:39,070 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-22 03:02:39,070 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:39,070 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:39,070 - DEBUG - Unzip image
2025-09-22 03:02:39,070 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:39,098 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-22 03:02:39,099 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-61126231']
2025-09-22 03:02:39,207 - INFO - Service template: ['default-domain', 'ctest-service_template_1-61126231'] deleted successfully.
2025-09-22 03:02:39,208 - INFO - Skip interface_detach for VM ctest-right_vm-53344739 in state BUILD
2025-09-22 03:02:39,208 - INFO - Deleting VM ctest-right_vm-53344739
2025-09-22 03:02:39,243 - ERROR - VM ctest-right_vm-53344739 has failed to come up
2025-09-22 03:02:39,243 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:39,243 - ERROR - VM ctest-right_vm-53344739 has failed to come up
2025-09-22 03:02:39,243 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:39,322 - INFO - Skip interface_detach for VM ctest-left_vm-98584801 in state BUILD
2025-09-22 03:02:39,322 - INFO - Deleting VM ctest-left_vm-98584801
2025-09-22 03:02:39,322 - ERROR - VM ctest-left_vm-98584801 has failed to come up
2025-09-22 03:02:39,322 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:39,322 - ERROR - VM ctest-left_vm-98584801 has failed to come up
2025-09-22 03:02:39,322 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:39,389 - INFO - Deleting VN service-right-vn-49178236
2025-09-22 03:02:39,436 - DEBUG - VN 0e29688a-f466-4cc9-b7ea-6200bd3fb62c still in use: Unable to complete operation on network 0e29688a-f466-4cc9-b7ea-6200bd3fb62c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6a533219-1fd8-48c9-9fb3-9c2ed89c124c']
2025-09-22 03:02:39,436 - WARNING - Deleting VN service-right-vn-49178236 failed..Will retry
2025-09-22 03:02:41,624 - DEBUG - Response for deleting network ()
2025-09-22 03:02:41,624 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ef1e249d-f439-4d03-aec9-f8b5e76c908b
2025-09-22 03:02:41,633 - DEBUG - Response Code: 404
2025-09-22 03:02:41,633 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:41,640 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:41,665 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:41,762 - INFO - Validated that VN service-right-vn-49178236 is not found in API Server
2025-09-22 03:02:41,773 - DEBUG - VN service-right-vn-49178236 is not present in Agent 10.0.0.33
2025-09-22 03:02:41,781 - DEBUG - VN service-right-vn-49178236 is not present in Agent 10.0.0.23
2025-09-22 03:02:41,781 - INFO - Validated that VN service-right-vn-49178236 is not in any agent
2025-09-22 03:02:41,810 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-49178236 info
2025-09-22 03:02:41,810 - INFO - Deleting VN service-left-vn-48125310
2025-09-22 03:02:41,986 - DEBUG - Response for deleting network ()
2025-09-22 03:02:41,986 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/794a2635-1295-4023-a180-4c415bcf5e3b
2025-09-22 03:02:41,992 - DEBUG - Response Code: 404
2025-09-22 03:02:41,992 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:41,998 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:42,021 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:42,118 - INFO - Validated that VN service-left-vn-48125310 is not found in API Server
2025-09-22 03:02:42,129 - DEBUG - VN service-left-vn-48125310 is not present in Agent 10.0.0.33
2025-09-22 03:02:42,138 - DEBUG - VN service-left-vn-48125310 is not present in Agent 10.0.0.23
2025-09-22 03:02:42,138 - INFO - Validated that VN service-left-vn-48125310 is not in any agent
2025-09-22 03:02:42,166 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-48125310 info
2025-09-22 03:02:42,166 - INFO - Deleting VN ctest-mgmt_vn-96492422
2025-09-22 03:02:42,324 - DEBUG - Response for deleting network ()
2025-09-22 03:02:42,324 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/78684d44-bc46-491f-b09c-8dbce16d7a28
2025-09-22 03:02:42,330 - DEBUG - Response Code: 404
2025-09-22 03:02:42,330 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:42,344 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:42,359 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:42,449 - INFO - Validated that VN ctest-mgmt_vn-96492422 is not found in API Server
2025-09-22 03:02:42,460 - DEBUG - VN ctest-mgmt_vn-96492422 is not present in Agent 10.0.0.33
2025-09-22 03:02:42,470 - DEBUG - VN ctest-mgmt_vn-96492422 is not present in Agent 10.0.0.23
2025-09-22 03:02:42,470 - INFO - Validated that VN ctest-mgmt_vn-96492422 is not in any agent
2025-09-22 03:02:42,498 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-96492422 info
2025-09-22 03:02:43,766 - DEBUG - No XMPP flaps were noticed during the test
2025-09-22 03:02:43,766 - INFO -
2025-09-22 03:02:43,766 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-09-22 03:02:43,766 - INFO - --------------------------------------------------------------------------------
2025-09-22 03:02:43,769 - INFO - ================================================================================
2025-09-22 03:02:43,769 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-09-22 03:02:45,036 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.193': '0'}} with
2025-09-22 03:02:45,036 - INFO - Initial checks done. Running the testcase now
2025-09-22 03:02:45,036 - INFO -
2025-09-22 03:02:45,353 - DEBUG - Response for create_network : {'network': {'id': 'bc7ebced-3441-40e5-89fe-24ffaf0e68bb', 'name': 'ctest-mgmt_vn-30580528', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'ctest-mgmt_vn-30580528'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:45,664 - DEBUG - Response for create_subnet : {'subnet': {'id': '90870fdc-41e0-4393-a4c0-8574def4ac43', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': 'bc7ebced-3441-40e5-89fe-24ffaf0e68bb', 'ip_version': 4, 'cidr': '145.224.83.128/26', 'allocation_pools': [{'start': '145.224.83.130', 'end': '145.224.83.190'}], 'gateway_ip': '145.224.83.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '145.224.83.130', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:45,686 - INFO - Created VN ctest-mgmt_vn-30580528
2025-09-22 03:02:45,700 - DEBUG - VN ctest-mgmt_vn-30580528 UUID is bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,779 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:45,786 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:45,796 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:45,891 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,903 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,913 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:45,922 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:45,929 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6a30770d-e0be-4c82-b170-4a249b037b7f
2025-09-22 03:02:45,938 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:45,938 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,946 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:45,963 - INFO - Verified VN network id 11 for VN bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,963 - INFO - Verifications in API Server for VN ctest-mgmt_vn-30580528 passed
2025-09-22 03:02:45,963 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:45,972 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:45,979 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:45,986 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6a30770d-e0be-4c82-b170-4a249b037b7f
2025-09-22 03:02:45,999 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13582501253165957349', 'uuid-lslong': '9943425707852851387'}, 'enable': 'true', 'created': '2025-09-22T03:02:45', 'last-modified': '2025-09-22T03:02:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418997'}
2025-09-22 03:02:46,007 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:46,014 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13582501253165957349', 'uuid-lslong': '9943425707852851387'}, 'enable': 'true', 'created': '2025-09-22T03:02:45', 'last-modified': '2025-09-22T03:02:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437912'}
2025-09-22 03:02:46,025 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:46,033 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13582501253165957349', 'uuid-lslong': '9943425707852851387'}, 'enable': 'true', 'created': '2025-09-22T03:02:45', 'last-modified': '2025-09-22T03:02:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454819'}
2025-09-22 03:02:46,040 - DEBUG - Route Targets: ['target:64512:8000004']
2025-09-22 03:02:46,041 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-30580528 passed
2025-09-22 03:02:46,041 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-30580528 in API_Server ======
2025-09-22 03:02:46,041 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:46,047 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:46,060 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:46,172 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bc7ebced-3441-40e5-89fe-24ffaf0e68bb
2025-09-22 03:02:46,185 - DEBUG - =>VN ctest-mgmt_vn-30580528 has no policy to be verified
2025-09-22 03:02:46,186 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:46,186 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:46,186 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:46,203 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528?flat'}
2025-09-22 03:02:46,203 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:46,203 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:46,220 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528?flat'}
2025-09-22 03:02:46,221 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:46,221 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:46,235 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528?flat'}
2025-09-22 03:02:46,235 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:ctest-mgmt_vn-30580528 is found in opserver
2025-09-22 03:02:46,260 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:46,275 - DEBUG - VRF ids for VN ctest-mgmt_vn-30580528: {}
2025-09-22 03:02:46,585 - DEBUG - Response for create_network : {'network': {'id': 'a956edce-69df-433c-a78f-34192c6010ba', 'name': 'ctest-left_vn-36167302', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'ctest-left_vn-36167302'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:46,865 - DEBUG - Response for create_subnet : {'subnet': {'id': '61af3aae-9812-482f-af7a-4d5ba48d39e0', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': 'a956edce-69df-433c-a78f-34192c6010ba', 'ip_version': 4, 'cidr': '172.157.103.64/26', 'allocation_pools': [{'start': '172.157.103.66', 'end': '172.157.103.126'}], 'gateway_ip': '172.157.103.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '172.157.103.66', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:46,886 - INFO - Created VN ctest-left_vn-36167302
2025-09-22 03:02:46,900 - DEBUG - VN ctest-left_vn-36167302 UUID is a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:46,981 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:46,986 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:46,996 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:47,084 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,098 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,107 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:47,114 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:47,120 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/37d84a60-d973-43a8-b171-d51b647eadcb
2025-09-22 03:02:47,128 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:47,128 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,136 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:47,151 - INFO - Verified VN network id 12 for VN a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,151 - INFO - Verifications in API Server for VN ctest-left_vn-36167302 passed
2025-09-22 03:02:47,152 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,161 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:47,168 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:47,174 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/37d84a60-d973-43a8-b171-d51b647eadcb
2025-09-22 03:02:47,189 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12202201711202222908', 'uuid-lslong': '12073926408726909114'}, 'enable': 'true', 'created': '2025-09-22T03:02:46', 'last-modified': '2025-09-22T03:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383425'}
2025-09-22 03:02:47,200 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:47,206 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12202201711202222908', 'uuid-lslong': '12073926408726909114'}, 'enable': 'true', 'created': '2025-09-22T03:02:46', 'last-modified': '2025-09-22T03:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406151'}
2025-09-22 03:02:47,212 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:47,218 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12202201711202222908', 'uuid-lslong': '12073926408726909114'}, 'enable': 'true', 'created': '2025-09-22T03:02:46', 'last-modified': '2025-09-22T03:02:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418446'}
2025-09-22 03:02:47,224 - DEBUG - Route Targets: ['target:64512:8000005']
2025-09-22 03:02:47,224 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-36167302 passed
2025-09-22 03:02:47,225 - DEBUG - ====Verifying policy data for ctest-left_vn-36167302 in API_Server ======
2025-09-22 03:02:47,225 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:47,230 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:47,240 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:47,334 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a956edce-69df-433c-a78f-34192c6010ba
2025-09-22 03:02:47,342 - DEBUG - =>VN ctest-left_vn-36167302 has no policy to be verified
2025-09-22 03:02:47,342 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:47,342 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:47,342 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:47,354 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302?flat'}
2025-09-22 03:02:47,354 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:47,354 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:47,370 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302?flat'}
2025-09-22 03:02:47,370 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:47,370 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:47,384 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302?flat'}
2025-09-22 03:02:47,384 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:ctest-left_vn-36167302 is found in opserver
2025-09-22 03:02:47,406 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:47,415 - DEBUG - VRF ids for VN ctest-left_vn-36167302: {}
2025-09-22 03:02:47,692 - DEBUG - Response for create_network : {'network': {'id': '77fcad75-ad2b-478e-b865-8ac031c7a078', 'name': 'ctest-right_vn-33512258', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'project_id': '0423666e04854a20b37cfc652ff2a4a7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38628394', 'ctest-right_vn-33512258'], 'port_security_enabled': True, 'description': ''}}
2025-09-22 03:02:47,939 - DEBUG - Response for create_subnet : {'subnet': {'id': '7caa6534-9492-47f9-bf36-1246d4a8541a', 'name': '', 'tenant_id': '0423666e04854a20b37cfc652ff2a4a7', 'network_id': '77fcad75-ad2b-478e-b865-8ac031c7a078', 'ip_version': 4, 'cidr': '116.165.14.0/26', 'allocation_pools': [{'start': '116.165.14.2', 'end': '116.165.14.62'}], 'gateway_ip': '116.165.14.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '116.165.14.2', 'tags': [], 'project_id': '0423666e04854a20b37cfc652ff2a4a7'}}
2025-09-22 03:02:47,960 - INFO - Created VN ctest-right_vn-33512258
2025-09-22 03:02:47,975 - DEBUG - VN ctest-right_vn-33512258 UUID is 77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,041 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:48,047 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:48,057 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:48,154 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,168 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,175 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:48,182 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:48,188 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4df9a8d2-44f8-4213-844e-faafbb7f2c9a
2025-09-22 03:02:48,193 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:48,193 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,200 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:48,220 - INFO - Verified VN network id 13 for VN 77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,220 - INFO - Verifications in API Server for VN ctest-right_vn-33512258 passed
2025-09-22 03:02:48,220 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,228 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:48,235 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:48,244 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4df9a8d2-44f8-4213-844e-faafbb7f2c9a
2025-09-22 03:02:48,259 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8645976105572583310', 'uuid-lslong': '13287178833700036728'}, 'enable': 'true', 'created': '2025-09-22T03:02:47', 'last-modified': '2025-09-22T03:02:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.371035'}
2025-09-22 03:02:48,264 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:48,270 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8645976105572583310', 'uuid-lslong': '13287178833700036728'}, 'enable': 'true', 'created': '2025-09-22T03:02:47', 'last-modified': '2025-09-22T03:02:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381915'}
2025-09-22 03:02:48,275 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:48,281 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38628394', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8645976105572583310', 'uuid-lslong': '13287178833700036728'}, 'enable': 'true', 'created': '2025-09-22T03:02:47', 'last-modified': '2025-09-22T03:02:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.390075'}
2025-09-22 03:02:48,287 - DEBUG - Route Targets: ['target:64512:8000006']
2025-09-22 03:02:48,287 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-33512258 passed
2025-09-22 03:02:48,287 - DEBUG - ====Verifying policy data for ctest-right_vn-33512258 in API_Server ======
2025-09-22 03:02:48,288 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:48,295 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:48,305 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:48,395 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/77fcad75-ad2b-478e-b865-8ac031c7a078
2025-09-22 03:02:48,403 - DEBUG - =>VN ctest-right_vn-33512258 has no policy to be verified
2025-09-22 03:02:48,404 - DEBUG - Verifying the vn in opserver
2025-09-22 03:02:48,404 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258 virtual network link through opserver 10.0.0.56
2025-09-22 03:02:48,404 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-09-22 03:02:48,414 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258?flat'}
2025-09-22 03:02:48,414 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258 virtual network link through opserver 10.0.0.38
2025-09-22 03:02:48,414 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-09-22 03:02:48,426 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258?flat'}
2025-09-22 03:02:48,426 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258 virtual network link through opserver 10.0.0.27
2025-09-22 03:02:48,426 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2025-09-22 03:02:48,439 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258?flat'}
2025-09-22 03:02:48,439 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38628394:ctest-right_vn-33512258 is found in opserver
2025-09-22 03:02:48,458 - DEBUG - Do not have enough data to verify VN in agent
2025-09-22 03:02:48,467 - DEBUG - VRF ids for VN ctest-right_vn-33512258: {}
2025-09-22 03:02:49,452 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-1)
2025-09-22 03:02:50,774 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4159-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4159-2)
2025-09-22 03:02:50,775 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-81214046']
2025-09-22 03:02:51,071 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-81214046'] created succesfully
2025-09-22 03:02:51,396 - DEBUG - Image by name tiny_nat_fw not found
2025-09-22 03:02:51,396 - DEBUG - Installing image tiny_nat_fw
2025-09-22 03:02:51,396 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-09-22 03:02:51,397 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:51,397 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:51,397 - DEBUG - Unzip image
2025-09-22 03:02:51,397 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-09-22 03:02:51,415 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-09-22 03:02:51,416 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-81214046']
2025-09-22 03:02:51,568 - INFO - Service template: ['default-domain', 'ctest-service_template_1-81214046'] deleted successfully.
2025-09-22 03:02:51,568 - INFO - Skip interface_detach for VM ctest-right_vm-10511530 in state BUILD
2025-09-22 03:02:51,568 - INFO - Deleting VM ctest-right_vm-10511530
2025-09-22 03:02:51,568 - ERROR - VM ctest-right_vm-10511530 has failed to come up
2025-09-22 03:02:51,568 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:51,568 - ERROR - VM ctest-right_vm-10511530 has failed to come up
2025-09-22 03:02:51,568 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:51,719 - INFO - Skip interface_detach for VM ctest-left_vm-14181256 in state BUILD
2025-09-22 03:02:51,719 - INFO - Deleting VM ctest-left_vm-14181256
2025-09-22 03:02:51,719 - ERROR - VM ctest-left_vm-14181256 has failed to come up
2025-09-22 03:02:51,719 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:51,719 - ERROR - VM ctest-left_vm-14181256 has failed to come up
2025-09-22 03:02:51,719 - ERROR - Nova failed to get host of the VM
2025-09-22 03:02:51,811 - INFO - Deleting VN ctest-right_vn-33512258
2025-09-22 03:02:51,863 - DEBUG - VN 77fcad75-ad2b-478e-b865-8ac031c7a078 still in use: Unable to complete operation on network 77fcad75-ad2b-478e-b865-8ac031c7a078. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3739898c-888b-4740-82a6-e1f37a9dbf04']
2025-09-22 03:02:51,863 - WARNING - Deleting VN ctest-right_vn-33512258 failed..Will retry
2025-09-22 03:02:54,036 - DEBUG - Response for deleting network ()
2025-09-22 03:02:54,036 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7d7f46e2-f167-466b-bd66-c6d4203a349e
2025-09-22 03:02:54,041 - DEBUG - Response Code: 404
2025-09-22 03:02:54,041 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:54,047 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:54,071 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:54,165 - INFO - Validated that VN ctest-right_vn-33512258 is not found in API Server
2025-09-22 03:02:54,177 - DEBUG - VN ctest-right_vn-33512258 is not present in Agent 10.0.0.33
2025-09-22 03:02:54,185 - DEBUG - VN ctest-right_vn-33512258 is not present in Agent 10.0.0.23
2025-09-22 03:02:54,185 - INFO - Validated that VN ctest-right_vn-33512258 is not in any agent
2025-09-22 03:02:54,218 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-33512258 info
2025-09-22 03:02:54,219 - INFO - Deleting VN ctest-left_vn-36167302
2025-09-22 03:02:54,392 - DEBUG - Response for deleting network ()
2025-09-22 03:02:54,393 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7ebd2e2e-28f4-4556-8c1e-1eddc0279d88
2025-09-22 03:02:54,398 - DEBUG - Response Code: 404
2025-09-22 03:02:54,398 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:54,403 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:54,414 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:54,498 - INFO - Validated that VN ctest-left_vn-36167302 is not found in API Server
2025-09-22 03:02:54,509 - DEBUG - VN ctest-left_vn-36167302 is not present in Agent 10.0.0.33
2025-09-22 03:02:54,519 - DEBUG - VN ctest-left_vn-36167302 is not present in Agent 10.0.0.23
2025-09-22 03:02:54,519 - INFO - Validated that VN ctest-left_vn-36167302 is not in any agent
2025-09-22 03:02:54,550 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-36167302 info
2025-09-22 03:02:54,550 - INFO - Deleting VN ctest-mgmt_vn-30580528
2025-09-22 03:02:54,709 - DEBUG - Response for deleting network ()
2025-09-22 03:02:54,709 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9a9f3dd-7d43-42b6-9e24-3da7ae70b068
2025-09-22 03:02:54,739 - DEBUG - Response Code: 404
2025-09-22 03:02:54,739 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-09-22 03:02:54,746 - DEBUG - Requesting: http://10.0.0.56:8082/domain/ccce3d88-d117-4380-892c-193ef8fcc90d
2025-09-22 03:02:54,764 - DEBUG - Requesting: http://10.0.0.56:8082/project/0423666e-0485-4a20-b37c-fc652ff2a4a7
2025-09-22 03:02:54,856 - INFO - Validated that VN ctest-mgmt_vn-30580528 is not found in API Server
2025-09-22 03:02:54,867 - DEBUG - VN ctest-mgmt_vn-30580528 is not present in Agent 10.0.0.33
2025-09-22 03:02:54,878 - DEBUG - VN ctest-mgmt_vn-30580528 is not present in Agent 10.0.0.23
2025-09-22 03:02:54,878 - INFO - Validated that VN ctest-mgmt_vn-30580528 is not in any agent
2025-09-22 03:02:54,913 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-30580528 info
2025-09-22 03:02:56,180 - DEBUG - No XMPP flaps were noticed during the test
2025-09-22 03:02:56,180 - INFO -
2025-09-22 03:02:56,181 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-09-22 03:02:56,181 - INFO - --------------------------------------------------------------------------------
2025-09-22 03:02:56,913 - INFO - Deleted project: ctest-TestSvcRegr-38628394, ID : 0423666e-0485-4a20-b37c-fc652ff2a4a7