]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-2)
2025-08-11 11:46:29,375 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-63805137']
2025-08-11 11:46:29,654 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-63805137'] created succesfully
2025-08-11 11:46:30,038 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 11:46:30,039 - DEBUG - Installing image tiny_nat_fw
2025-08-11 11:46:30,039 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 11:46:30,039 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-11 11:46:30,039 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-11 11:46:30,053 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:46:30,084 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:46:30,085 - DEBUG - Unzip image
2025-08-11 11:46:30,085 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:46:30,113 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 11:46:30,115 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-63805137']
2025-08-11 11:46:30,232 - INFO - Service template: ['default-domain', 'ctest-service_template_1-63805137'] deleted successfully.
2025-08-11 11:46:30,232 - INFO - Skip interface_detach for VM ctest-right_vm-30287700 in state BUILD
2025-08-11 11:46:30,232 - INFO - Deleting VM ctest-right_vm-30287700
2025-08-11 11:46:30,262 - ERROR - VM ctest-right_vm-30287700 has failed to come up
2025-08-11 11:46:30,262 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:30,262 - ERROR - VM ctest-right_vm-30287700 has failed to come up
2025-08-11 11:46:30,262 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:30,324 - INFO - Skip interface_detach for VM ctest-left_vm-00400703 in state BUILD
2025-08-11 11:46:30,324 - INFO - Deleting VM ctest-left_vm-00400703
2025-08-11 11:46:30,324 - ERROR - VM ctest-left_vm-00400703 has failed to come up
2025-08-11 11:46:30,324 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:30,325 - ERROR - VM ctest-left_vm-00400703 has failed to come up
2025-08-11 11:46:30,325 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:30,390 - INFO - Deleting VN ctest-right_vn-46045349
2025-08-11 11:46:30,437 - DEBUG - VN 860997ba-2303-4193-bd4e-062d8f7ef52c still in use: Unable to complete operation on network 860997ba-2303-4193-bd4e-062d8f7ef52c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-635a85d2-efdc-4ba9-a520-696994e94fbe']
2025-08-11 11:46:30,437 - WARNING - Deleting VN ctest-right_vn-46045349 failed..Will retry
2025-08-11 11:46:32,578 - DEBUG - Response for deleting network ()
2025-08-11 11:46:32,579 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/30a63860-ab4e-4ec6-9a5d-8d4551abf0ca
2025-08-11 11:46:32,586 - DEBUG - Response Code: 404
2025-08-11 11:46:32,587 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:32,606 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:32,626 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:32,726 - INFO - Validated that VN ctest-right_vn-46045349 is not found in API Server
2025-08-11 11:46:32,737 - DEBUG - VN ctest-right_vn-46045349 is not present in Agent 10.0.0.19
2025-08-11 11:46:32,748 - DEBUG - VN ctest-right_vn-46045349 is not present in Agent 10.0.0.41
2025-08-11 11:46:32,748 - INFO - Validated that VN ctest-right_vn-46045349 is not in any agent
2025-08-11 11:46:32,779 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-46045349 info
2025-08-11 11:46:32,781 - INFO - Deleting VN ctest-left_vn-86866752
2025-08-11 11:46:32,905 - DEBUG - Response for deleting network ()
2025-08-11 11:46:32,906 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/763081a7-af0a-46a0-b74a-73cb408b6add
2025-08-11 11:46:32,914 - DEBUG - Response Code: 404
2025-08-11 11:46:32,914 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:32,921 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:32,937 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:33,024 - INFO - Validated that VN ctest-left_vn-86866752 is not found in API Server
2025-08-11 11:46:33,036 - DEBUG - VN ctest-left_vn-86866752 is not present in Agent 10.0.0.19
2025-08-11 11:46:33,054 - DEBUG - VN ctest-left_vn-86866752 is not present in Agent 10.0.0.41
2025-08-11 11:46:33,054 - INFO - Validated that VN ctest-left_vn-86866752 is not in any agent
2025-08-11 11:46:33,086 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-86866752 info
2025-08-11 11:46:33,086 - INFO - Deleting VN ctest-mgmt_vn-90630140
2025-08-11 11:46:33,203 - DEBUG - Response for deleting network ()
2025-08-11 11:46:33,204 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d1797794-b987-4a55-b7a1-dfffc5f8c488
2025-08-11 11:46:33,209 - DEBUG - Response Code: 404
2025-08-11 11:46:33,209 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:33,214 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:33,232 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:33,313 - INFO - Validated that VN ctest-mgmt_vn-90630140 is not found in API Server
2025-08-11 11:46:33,328 - DEBUG - VN ctest-mgmt_vn-90630140 is not present in Agent 10.0.0.19
2025-08-11 11:46:33,349 - DEBUG - VN ctest-mgmt_vn-90630140 is not present in Agent 10.0.0.41
2025-08-11 11:46:33,349 - INFO - Validated that VN ctest-mgmt_vn-90630140 is not in any agent
2025-08-11 11:46:33,382 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-90630140 info
2025-08-11 11:46:33,382 - INFO - Deleting VM ctest-vm-in-third-vn-36696292
2025-08-11 11:46:33,444 - INFO - Deleting VN ctest-third-vn-43276795
2025-08-11 11:46:33,482 - DEBUG - VN 583485b5-423d-4744-8f4b-5c2bf76d5685 still in use: Unable to complete operation on network 583485b5-423d-4744-8f4b-5c2bf76d5685. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b3f1f0fb-7b00-405e-bb04-551ecfc8da7f']
2025-08-11 11:46:33,482 - WARNING - Deleting VN ctest-third-vn-43276795 failed..Will retry
2025-08-11 11:46:35,609 - DEBUG - Response for deleting network ()
2025-08-11 11:46:36,869 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 11:46:36,869 - INFO -
2025-08-11 11:46:36,869 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:23]
2025-08-11 11:46:36,869 - INFO - --------------------------------------------------------------------------------
2025-08-11 11:46:36,872 - INFO - ================================================================================
2025-08-11 11:46:36,872 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-11 11:46:38,113 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-11 11:46:38,113 - INFO - Initial checks done. Running the testcase now
2025-08-11 11:46:38,113 - INFO -
2025-08-11 11:46:38,379 - DEBUG - Response for create_network : {'network': {'id': 'e829a2ce-a416-44f4-8cd2-f950709d1d7f', 'name': 'ctest-mgmt_vn-47579276', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-05811440', 'ctest-mgmt_vn-47579276'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:38,612 - DEBUG - Response for create_subnet : {'subnet': {'id': '06026d33-2933-43cf-a07c-896a64e6d6ef', 'name': '', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'network_id': 'e829a2ce-a416-44f4-8cd2-f950709d1d7f', 'ip_version': 4, 'cidr': '219.240.243.0/26', 'allocation_pools': [{'start': '219.240.243.2', 'end': '219.240.243.62'}], 'gateway_ip': '219.240.243.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '219.240.243.2', 'tags': [], 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6'}}
2025-08-11 11:46:38,634 - INFO - Created VN ctest-mgmt_vn-47579276
2025-08-11 11:46:38,644 - DEBUG - VN ctest-mgmt_vn-47579276 UUID is e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,707 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:38,713 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:38,725 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:38,823 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,835 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,844 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:38,852 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:38,859 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6e7878b7-bebc-49bb-a846-1f26b42fb16a
2025-08-11 11:46:38,866 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:38,866 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,876 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:38,890 - INFO - Verified VN network id 11 for VN e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,890 - INFO - Verifications in API Server for VN ctest-mgmt_vn-47579276 passed
2025-08-11 11:46:38,890 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:38,899 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:38,906 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:38,912 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6e7878b7-bebc-49bb-a846-1f26b42fb16a
2025-08-11 11:46:38,924 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16729081299244303604', 'uuid-lslong': '10147447034301193599'}, 'enable': 'true', 'created': '2025-08-11T11:46:38', 'last-modified': '2025-08-11T11:46:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.349029'}
2025-08-11 11:46:38,930 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:38,936 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16729081299244303604', 'uuid-lslong': '10147447034301193599'}, 'enable': 'true', 'created': '2025-08-11T11:46:38', 'last-modified': '2025-08-11T11:46:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362983'}
2025-08-11 11:46:38,942 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:38,948 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16729081299244303604', 'uuid-lslong': '10147447034301193599'}, 'enable': 'true', 'created': '2025-08-11T11:46:38', 'last-modified': '2025-08-11T11:46:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367277'}
2025-08-11 11:46:38,953 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:38,954 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-47579276 passed
2025-08-11 11:46:38,954 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-47579276 in API_Server ======
2025-08-11 11:46:38,954 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:38,959 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:38,970 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:39,059 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e829a2ce-a416-44f4-8cd2-f950709d1d7f
2025-08-11 11:46:39,070 - DEBUG - =>VN ctest-mgmt_vn-47579276 has no policy to be verified
2025-08-11 11:46:39,070 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:39,070 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:39,070 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:39,082 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276?flat'}
2025-08-11 11:46:39,082 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:39,082 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:39,093 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276?flat'}
2025-08-11 11:46:39,093 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:39,093 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:39,103 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276?flat'}
2025-08-11 11:46:39,103 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-05811440:ctest-mgmt_vn-47579276 is found in opserver
2025-08-11 11:46:39,124 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:39,133 - DEBUG - VRF ids for VN ctest-mgmt_vn-47579276: {}
2025-08-11 11:46:39,405 - DEBUG - Response for create_network : {'network': {'id': 'e2e50560-c8a6-4239-bdd4-bc87e731aef1', 'name': 'ctest-left_vn-21959851', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-05811440', 'ctest-left_vn-21959851'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:39,658 - DEBUG - Response for create_subnet : {'subnet': {'id': '0931b220-0963-4f24-a44e-eff5d5711cfe', 'name': '', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'network_id': 'e2e50560-c8a6-4239-bdd4-bc87e731aef1', 'ip_version': 4, 'cidr': '132.17.106.128/26', 'allocation_pools': [{'start': '132.17.106.130', 'end': '132.17.106.190'}], 'gateway_ip': '132.17.106.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '132.17.106.130', 'tags': [], 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6'}}
2025-08-11 11:46:39,679 - INFO - Created VN ctest-left_vn-21959851
2025-08-11 11:46:39,692 - DEBUG - VN ctest-left_vn-21959851 UUID is e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,755 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:39,761 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:39,772 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:39,872 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,889 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,904 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:39,915 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:39,924 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9114adde-378d-4921-8d85-c386236f09d1
2025-08-11 11:46:39,932 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:39,932 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,942 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:39,959 - INFO - Verified VN network id 12 for VN e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,960 - INFO - Verifications in API Server for VN ctest-left_vn-21959851 passed
2025-08-11 11:46:39,960 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:39,970 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:39,977 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:39,985 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9114adde-378d-4921-8d85-c386236f09d1
2025-08-11 11:46:39,997 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16349479935479792185', 'uuid-lslong': '13678765260116438769'}, 'enable': 'true', 'created': '2025-08-11T11:46:39', 'last-modified': '2025-08-11T11:46:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.394076'}
2025-08-11 11:46:40,003 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:40,008 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16349479935479792185', 'uuid-lslong': '13678765260116438769'}, 'enable': 'true', 'created': '2025-08-11T11:46:39', 'last-modified': '2025-08-11T11:46:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427770'}
2025-08-11 11:46:40,015 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:40,021 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16349479935479792185', 'uuid-lslong': '13678765260116438769'}, 'enable': 'true', 'created': '2025-08-11T11:46:39', 'last-modified': '2025-08-11T11:46:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.445692'}
2025-08-11 11:46:40,028 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:40,028 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-21959851 passed
2025-08-11 11:46:40,029 - DEBUG - ====Verifying policy data for ctest-left_vn-21959851 in API_Server ======
2025-08-11 11:46:40,029 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:40,035 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:40,048 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:40,137 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e2e50560-c8a6-4239-bdd4-bc87e731aef1
2025-08-11 11:46:40,152 - DEBUG - =>VN ctest-left_vn-21959851 has no policy to be verified
2025-08-11 11:46:40,152 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:40,152 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:40,152 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:40,163 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851?flat'}
2025-08-11 11:46:40,163 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:40,163 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:40,175 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851?flat'}
2025-08-11 11:46:40,175 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:40,175 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:40,187 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851?flat'}
2025-08-11 11:46:40,187 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-05811440:ctest-left_vn-21959851 is found in opserver
2025-08-11 11:46:40,207 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:40,216 - DEBUG - VRF ids for VN ctest-left_vn-21959851: {}
2025-08-11 11:46:40,488 - DEBUG - Response for create_network : {'network': {'id': '622ea005-bce9-46d0-b468-ca71b244d9cd', 'name': 'ctest-right_vn-56221202', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-05811440', 'ctest-right_vn-56221202'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:40,730 - DEBUG - Response for create_subnet : {'subnet': {'id': '7b669bfb-5171-4c39-a783-8e35093cbb8c', 'name': '', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'network_id': '622ea005-bce9-46d0-b468-ca71b244d9cd', 'ip_version': 4, 'cidr': '158.4.108.192/26', 'allocation_pools': [{'start': '158.4.108.194', 'end': '158.4.108.254'}], 'gateway_ip': '158.4.108.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '158.4.108.194', 'tags': [], 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6'}}
2025-08-11 11:46:40,750 - INFO - Created VN ctest-right_vn-56221202
2025-08-11 11:46:40,760 - DEBUG - VN ctest-right_vn-56221202 UUID is 622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:40,824 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:40,831 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:40,842 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:40,934 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:40,948 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:40,957 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:40,964 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:40,970 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/590d3cdf-97c3-44b1-bb64-2b0f54a87dbb
2025-08-11 11:46:40,976 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:46:40,976 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:40,984 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:40,999 - INFO - Verified VN network id 13 for VN 622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:40,999 - INFO - Verifications in API Server for VN ctest-right_vn-56221202 passed
2025-08-11 11:46:40,999 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:41,008 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:41,014 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:41,021 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/590d3cdf-97c3-44b1-bb64-2b0f54a87dbb
2025-08-11 11:46:41,033 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7074768011150313168', 'uuid-lslong': '12999862914075908557'}, 'enable': 'true', 'created': '2025-08-11T11:46:40', 'last-modified': '2025-08-11T11:46:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.355774'}
2025-08-11 11:46:41,039 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:46:41,045 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7074768011150313168', 'uuid-lslong': '12999862914075908557'}, 'enable': 'true', 'created': '2025-08-11T11:46:40', 'last-modified': '2025-08-11T11:46:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370930'}
2025-08-11 11:46:41,051 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:46:41,056 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7074768011150313168', 'uuid-lslong': '12999862914075908557'}, 'enable': 'true', 'created': '2025-08-11T11:46:40', 'last-modified': '2025-08-11T11:46:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384146'}
2025-08-11 11:46:41,062 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:46:41,062 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-56221202 passed
2025-08-11 11:46:41,062 - DEBUG - ====Verifying policy data for ctest-right_vn-56221202 in API_Server ======
2025-08-11 11:46:41,062 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:41,068 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:41,078 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:41,170 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/622ea005-bce9-46d0-b468-ca71b244d9cd
2025-08-11 11:46:41,179 - DEBUG - =>VN ctest-right_vn-56221202 has no policy to be verified
2025-08-11 11:46:41,179 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:41,179 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:41,179 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:41,191 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202?flat'}
2025-08-11 11:46:41,191 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:41,191 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:41,202 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202?flat'}
2025-08-11 11:46:41,203 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:41,203 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:41,213 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202?flat'}
2025-08-11 11:46:41,213 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-05811440:ctest-right_vn-56221202 is found in opserver
2025-08-11 11:46:41,233 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:41,243 - DEBUG - VRF ids for VN ctest-right_vn-56221202: {}
2025-08-11 11:46:41,476 - DEBUG - Response for create_network : {'network': {'id': '7dd7ec14-6b22-4e70-bc71-5d7b576fa024', 'name': 'ctest-trans_left_vn-11694026', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-05811440', 'ctest-trans_left_vn-11694026'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:41,723 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f5a6228f-0555-4568-867b-046d5972dec3', 'name': '', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'network_id': '7dd7ec14-6b22-4e70-bc71-5d7b576fa024', 'ip_version': 4, 'cidr': '205.233.207.0/26', 'allocation_pools': [{'start': '205.233.207.2', 'end': '205.233.207.62'}], 'gateway_ip': '205.233.207.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '205.233.207.2', 'tags': [], 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6'}}
2025-08-11 11:46:41,741 - INFO - Created VN ctest-trans_left_vn-11694026
2025-08-11 11:46:41,752 - DEBUG - VN ctest-trans_left_vn-11694026 UUID is 7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,817 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:41,822 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:41,834 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:41,925 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,937 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,945 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:41,953 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:41,959 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/626debc2-1ff1-409d-8731-8ce02ef31bd7
2025-08-11 11:46:41,966 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 11:46:41,966 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,974 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:41,988 - INFO - Verified VN network id 14 for VN 7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,988 - INFO - Verifications in API Server for VN ctest-trans_left_vn-11694026 passed
2025-08-11 11:46:41,988 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:41,997 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:42,003 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:42,009 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/626debc2-1ff1-409d-8731-8ce02ef31bd7
2025-08-11 11:46:42,022 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9067975947174694512', 'uuid-lslong': '13578737135828049956'}, 'enable': 'true', 'created': '2025-08-11T11:46:41', 'last-modified': '2025-08-11T11:46:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.351561'}
2025-08-11 11:46:42,027 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 11:46:42,035 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9067975947174694512', 'uuid-lslong': '13578737135828049956'}, 'enable': 'true', 'created': '2025-08-11T11:46:41', 'last-modified': '2025-08-11T11:46:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362318'}
2025-08-11 11:46:42,041 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 11:46:42,046 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9067975947174694512', 'uuid-lslong': '13578737135828049956'}, 'enable': 'true', 'created': '2025-08-11T11:46:41', 'last-modified': '2025-08-11T11:46:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372006'}
2025-08-11 11:46:42,053 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 11:46:42,053 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-11694026 passed
2025-08-11 11:46:42,053 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-11694026 in API_Server ======
2025-08-11 11:46:42,053 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:42,058 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:42,069 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:42,161 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7dd7ec14-6b22-4e70-bc71-5d7b576fa024
2025-08-11 11:46:42,172 - DEBUG - =>VN ctest-trans_left_vn-11694026 has no policy to be verified
2025-08-11 11:46:42,172 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:42,173 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:42,173 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:42,185 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026?flat'}
2025-08-11 11:46:42,185 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:42,185 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:42,196 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026?flat'}
2025-08-11 11:46:42,196 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:42,196 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:42,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026?flat'}
2025-08-11 11:46:42,207 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-05811440:ctest-trans_left_vn-11694026 is found in opserver
2025-08-11 11:46:42,227 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:42,237 - DEBUG - VRF ids for VN ctest-trans_left_vn-11694026: {}
2025-08-11 11:46:42,459 - DEBUG - Response for create_network : {'network': {'id': 'a90eda24-0d48-4bf7-a24d-35938af050c3', 'name': 'ctest-trans_right_vn-60940944', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-05811440', 'ctest-trans_right_vn-60940944'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:42,711 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ba35a2f0-0db8-43ba-9167-24b393ce1496', 'name': '', 'tenant_id': 'ea1a37ada342408f9beed28d4e1f10b6', 'network_id': 'a90eda24-0d48-4bf7-a24d-35938af050c3', 'ip_version': 4, 'cidr': '95.211.233.192/26', 'allocation_pools': [{'start': '95.211.233.194', 'end': '95.211.233.254'}], 'gateway_ip': '95.211.233.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.211.233.194', 'tags': [], 'project_id': 'ea1a37ada342408f9beed28d4e1f10b6'}}
2025-08-11 11:46:42,732 - INFO - Created VN ctest-trans_right_vn-60940944
2025-08-11 11:46:42,744 - DEBUG - VN ctest-trans_right_vn-60940944 UUID is a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,804 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:42,810 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:42,822 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:42,913 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,925 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,935 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:42,941 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:42,949 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4f136cbd-ff52-4d34-ab19-81802b607711
2025-08-11 11:46:42,955 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 11:46:42,955 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,963 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:42,977 - INFO - Verified VN network id 15 for VN a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,977 - INFO - Verifications in API Server for VN ctest-trans_right_vn-60940944 passed
2025-08-11 11:46:42,977 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:42,986 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:42,992 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:42,999 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4f136cbd-ff52-4d34-ab19-81802b607711
2025-08-11 11:46:43,010 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12181913890460290039', 'uuid-lslong': '11695062715158515907'}, 'enable': 'true', 'created': '2025-08-11T11:46:42', 'last-modified': '2025-08-11T11:46:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.342270'}
2025-08-11 11:46:43,017 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 11:46:43,022 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12181913890460290039', 'uuid-lslong': '11695062715158515907'}, 'enable': 'true', 'created': '2025-08-11T11:46:42', 'last-modified': '2025-08-11T11:46:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.361806'}
2025-08-11 11:46:43,028 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 11:46:43,033 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-05811440', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12181913890460290039', 'uuid-lslong': '11695062715158515907'}, 'enable': 'true', 'created': '2025-08-11T11:46:42', 'last-modified': '2025-08-11T11:46:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.373222'}
2025-08-11 11:46:43,040 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 11:46:43,040 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-60940944 passed
2025-08-11 11:46:43,040 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-60940944 in API_Server ======
2025-08-11 11:46:43,040 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:43,046 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:43,056 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:43,153 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/a90eda24-0d48-4bf7-a24d-35938af050c3
2025-08-11 11:46:43,163 - DEBUG - =>VN ctest-trans_right_vn-60940944 has no policy to be verified
2025-08-11 11:46:43,163 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:43,163 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:43,163 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:43,175 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944?flat'}
2025-08-11 11:46:43,175 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:43,175 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:43,187 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944?flat'}
2025-08-11 11:46:43,187 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:43,187 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:43,198 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944?flat'}
2025-08-11 11:46:43,198 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-05811440:ctest-trans_right_vn-60940944 is found in opserver
2025-08-11 11:46:43,219 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:43,228 - DEBUG - VRF ids for VN ctest-trans_right_vn-60940944: {}
2025-08-11 11:46:44,179 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-2)
2025-08-11 11:46:45,366 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-1)
2025-08-11 11:46:45,366 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-28913802']
2025-08-11 11:46:45,662 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-28913802'] created succesfully
2025-08-11 11:46:46,012 - DEBUG - Image by name tiny_trans_fw not found
2025-08-11 11:46:46,012 - DEBUG - Installing image tiny_trans_fw
2025-08-11 11:46:46,012 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-11 11:46:46,013 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-11 11:46:46,013 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-11 11:46:46,030 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 11:46:46,082 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 11:46:46,084 - DEBUG - Unzip image
2025-08-11 11:46:46,084 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 11:46:46,142 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-11 11:46:46,143 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-28913802']
2025-08-11 11:46:46,252 - INFO - Service template: ['default-domain', 'ctest-service_template_1-28913802'] deleted successfully.
2025-08-11 11:46:46,252 - INFO - Skip interface_detach for VM ctest-right_vm-95631082 in state BUILD
2025-08-11 11:46:46,253 - INFO - Deleting VM ctest-right_vm-95631082
2025-08-11 11:46:46,253 - ERROR - VM ctest-right_vm-95631082 has failed to come up
2025-08-11 11:46:46,253 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:46,253 - ERROR - VM ctest-right_vm-95631082 has failed to come up
2025-08-11 11:46:46,254 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:46,342 - INFO - Skip interface_detach for VM ctest-left_vm-03302371 in state BUILD
2025-08-11 11:46:46,343 - INFO - Deleting VM ctest-left_vm-03302371
2025-08-11 11:46:46,343 - ERROR - VM ctest-left_vm-03302371 has failed to come up
2025-08-11 11:46:46,343 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:46,343 - ERROR - VM ctest-left_vm-03302371 has failed to come up
2025-08-11 11:46:46,343 - ERROR - Nova failed to get host of the VM
2025-08-11 11:46:46,404 - INFO - Deleting VN ctest-trans_right_vn-60940944
2025-08-11 11:46:46,567 - DEBUG - Response for deleting network ()
2025-08-11 11:46:46,567 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/cd2b0e74-db77-4d72-8215-849458fc1e65
2025-08-11 11:46:46,576 - DEBUG - Response Code: 404
2025-08-11 11:46:46,576 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:46,582 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:46,622 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:46,720 - INFO - Validated that VN ctest-trans_right_vn-60940944 is not found in API Server
2025-08-11 11:46:46,737 - DEBUG - VN ctest-trans_right_vn-60940944 is not present in Agent 10.0.0.19
2025-08-11 11:46:46,749 - DEBUG - VN ctest-trans_right_vn-60940944 is not present in Agent 10.0.0.41
2025-08-11 11:46:46,749 - INFO - Validated that VN ctest-trans_right_vn-60940944 is not in any agent
2025-08-11 11:46:46,785 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-60940944 info
2025-08-11 11:46:46,786 - INFO - Deleting VN ctest-trans_left_vn-11694026
2025-08-11 11:46:46,943 - DEBUG - Response for deleting network ()
2025-08-11 11:46:46,943 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6abd9e93-344c-4d49-9aaf-9a1bfedc2b23
2025-08-11 11:46:46,953 - DEBUG - Response Code: 404
2025-08-11 11:46:46,953 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:46,965 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:46,983 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:47,105 - INFO - Validated that VN ctest-trans_left_vn-11694026 is not found in API Server
2025-08-11 11:46:47,124 - DEBUG - VN ctest-trans_left_vn-11694026 is not present in Agent 10.0.0.19
2025-08-11 11:46:47,140 - DEBUG - VN ctest-trans_left_vn-11694026 is not present in Agent 10.0.0.41
2025-08-11 11:46:47,141 - INFO - Validated that VN ctest-trans_left_vn-11694026 is not in any agent
2025-08-11 11:46:47,198 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-11694026 info
2025-08-11 11:46:47,199 - INFO - Deleting VN ctest-right_vn-56221202
2025-08-11 11:46:47,244 - DEBUG - VN 622ea005-bce9-46d0-b468-ca71b244d9cd still in use: Unable to complete operation on network 622ea005-bce9-46d0-b468-ca71b244d9cd. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-491e3d02-ff90-4c94-8d4d-ac645fb3f92f']
2025-08-11 11:46:47,244 - WARNING - Deleting VN ctest-right_vn-56221202 failed..Will retry
2025-08-11 11:46:49,418 - DEBUG - Response for deleting network ()
2025-08-11 11:46:49,418 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e9be659a-5ec1-47c3-ab75-c9d5c2a83220
2025-08-11 11:46:49,428 - DEBUG - Response Code: 404
2025-08-11 11:46:49,428 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:49,436 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:49,494 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:49,607 - INFO - Validated that VN ctest-right_vn-56221202 is not found in API Server
2025-08-11 11:46:49,619 - DEBUG - VN ctest-right_vn-56221202 is not present in Agent 10.0.0.19
2025-08-11 11:46:49,629 - DEBUG - VN ctest-right_vn-56221202 is not present in Agent 10.0.0.41
2025-08-11 11:46:49,630 - INFO - Validated that VN ctest-right_vn-56221202 is not in any agent
2025-08-11 11:46:49,660 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-56221202 info
2025-08-11 11:46:49,660 - INFO - Deleting VN ctest-left_vn-21959851
2025-08-11 11:46:49,799 - DEBUG - Response for deleting network ()
2025-08-11 11:46:49,799 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d903a545-e65d-4fb1-911d-aa24d6098692
2025-08-11 11:46:49,805 - DEBUG - Response Code: 404
2025-08-11 11:46:49,805 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:49,810 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:49,824 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:49,927 - INFO - Validated that VN ctest-left_vn-21959851 is not found in API Server
2025-08-11 11:46:49,938 - DEBUG - VN ctest-left_vn-21959851 is not present in Agent 10.0.0.19
2025-08-11 11:46:49,950 - DEBUG - VN ctest-left_vn-21959851 is not present in Agent 10.0.0.41
2025-08-11 11:46:49,950 - INFO - Validated that VN ctest-left_vn-21959851 is not in any agent
2025-08-11 11:46:49,989 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-21959851 info
2025-08-11 11:46:49,989 - INFO - Deleting VN ctest-mgmt_vn-47579276
2025-08-11 11:46:50,140 - DEBUG - Response for deleting network ()
2025-08-11 11:46:50,141 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/5d58ea2c-3e06-4ef8-abf6-54432747d51e
2025-08-11 11:46:50,146 - DEBUG - Response Code: 404
2025-08-11 11:46:50,147 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:50,154 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:50,165 - DEBUG - Requesting: http://10.0.0.56:8082/project/ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:50,266 - INFO - Validated that VN ctest-mgmt_vn-47579276 is not found in API Server
2025-08-11 11:46:50,277 - DEBUG - VN ctest-mgmt_vn-47579276 is not present in Agent 10.0.0.19
2025-08-11 11:46:50,288 - DEBUG - VN ctest-mgmt_vn-47579276 is not present in Agent 10.0.0.41
2025-08-11 11:46:50,288 - INFO - Validated that VN ctest-mgmt_vn-47579276 is not in any agent
2025-08-11 11:46:50,326 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-47579276 info
2025-08-11 11:46:51,572 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 11:46:51,572 - INFO -
2025-08-11 11:46:51,572 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-08-11 11:46:51,573 - INFO - --------------------------------------------------------------------------------
2025-08-11 11:46:52,209 - INFO - Deleted project: ctest-TestSvcRegr-05811440, ID : ea1a37ad-a342-408f-9bee-d28d4e1f10b6
2025-08-11 11:46:52,343 - INFO - Domain Default found not creating
2025-08-11 11:46:52,466 - INFO - Project ctest-TestSvcRegr-96759272 not found, creating it
2025-08-11 11:46:52,973 - INFO - Created Project:ctest-TestSvcRegr-96759272, ID : 1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:46:54,287 - INFO - ================================================================================
2025-08-11 11:46:54,287 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-11 11:46:55,541 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-11 11:46:55,541 - INFO - Initial checks done. Running the testcase now
2025-08-11 11:46:55,541 - INFO -
2025-08-11 11:46:56,152 - DEBUG - Response for create_network : {'network': {'id': '8029d015-4823-46bc-bcc6-6ef7bcdb44e6', 'name': 'ctest-mgmt_vn-19417767', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'ctest-mgmt_vn-19417767'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:56,430 - DEBUG - Response for create_subnet : {'subnet': {'id': '21c04269-51d8-4452-bbee-27a20fe6515d', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': '8029d015-4823-46bc-bcc6-6ef7bcdb44e6', 'ip_version': 4, 'cidr': '129.179.253.192/26', 'allocation_pools': [{'start': '129.179.253.194', 'end': '129.179.253.254'}], 'gateway_ip': '129.179.253.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.179.253.194', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:46:56,451 - INFO - Created VN ctest-mgmt_vn-19417767
2025-08-11 11:46:56,463 - DEBUG - VN ctest-mgmt_vn-19417767 UUID is 8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:56,533 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:56,824 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:56,850 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:56,863 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:46:56,953 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:56,965 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:56,973 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:46:56,981 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:46:56,988 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/2bb4edfe-786f-418d-bea6-03dad5a08cab
2025-08-11 11:46:56,994 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:56,994 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:57,002 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:46:57,016 - INFO - Verified VN network id 11 for VN 8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:57,016 - INFO - Verifications in API Server for VN ctest-mgmt_vn-19417767 passed
2025-08-11 11:46:57,016 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:57,025 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:46:57,031 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:46:57,037 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/2bb4edfe-786f-418d-bea6-03dad5a08cab
2025-08-11 11:46:57,050 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9235141300723074748', 'uuid-lslong': '13602681734823625958'}, 'enable': 'true', 'created': '2025-08-11T11:46:56', 'last-modified': '2025-08-11T11:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.664317'}
2025-08-11 11:46:57,056 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:57,062 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9235141300723074748', 'uuid-lslong': '13602681734823625958'}, 'enable': 'true', 'created': '2025-08-11T11:46:56', 'last-modified': '2025-08-11T11:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.677244'}
2025-08-11 11:46:57,069 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:57,074 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9235141300723074748', 'uuid-lslong': '13602681734823625958'}, 'enable': 'true', 'created': '2025-08-11T11:46:56', 'last-modified': '2025-08-11T11:46:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.689600'}
2025-08-11 11:46:57,080 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:46:57,080 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-19417767 passed
2025-08-11 11:46:57,080 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-19417767 in API_Server ======
2025-08-11 11:46:57,080 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:57,086 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:57,097 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:46:57,182 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8029d015-4823-46bc-bcc6-6ef7bcdb44e6
2025-08-11 11:46:57,191 - DEBUG - =>VN ctest-mgmt_vn-19417767 has no policy to be verified
2025-08-11 11:46:57,191 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:57,191 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:57,191 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:57,482 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:57,534 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767?flat'}
2025-08-11 11:46:57,534 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:57,534 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:57,822 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:57,872 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767?flat'}
2025-08-11 11:46:57,872 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:57,872 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:58,158 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:58,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767?flat'}
2025-08-11 11:46:58,207 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-19417767 is found in opserver
2025-08-11 11:46:58,227 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:58,237 - DEBUG - VRF ids for VN ctest-mgmt_vn-19417767: {}
2025-08-11 11:46:58,471 - DEBUG - Response for create_network : {'network': {'id': 'e7aaa901-5638-4716-896e-20f75ee19770', 'name': 'service-left-vn-23117198', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'service-left-vn-23117198'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:46:58,668 - DEBUG - Response for create_subnet : {'subnet': {'id': '30165122-2f58-4b0b-bbb6-ec4496da96c8', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': 'e7aaa901-5638-4716-896e-20f75ee19770', 'ip_version': 4, 'cidr': '179.61.189.128/26', 'allocation_pools': [{'start': '179.61.189.130', 'end': '179.61.189.190'}], 'gateway_ip': '179.61.189.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '179.61.189.130', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:46:58,692 - INFO - Created VN service-left-vn-23117198
2025-08-11 11:46:58,704 - DEBUG - VN service-left-vn-23117198 UUID is e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:58,832 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:58,849 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:58,876 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:46:59,007 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,041 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,070 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:46:59,088 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:46:59,097 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8f286082-5c9b-4bbf-9ff9-c3a4f800ae78
2025-08-11 11:46:59,114 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:59,114 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,135 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:46:59,174 - INFO - Verified VN network id 12 for VN e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,175 - INFO - Verifications in API Server for VN service-left-vn-23117198 passed
2025-08-11 11:46:59,175 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,197 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:46:59,221 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:46:59,246 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8f286082-5c9b-4bbf-9ff9-c3a4f800ae78
2025-08-11 11:46:59,285 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16693340792008754966', 'uuid-lslong': '9902888877455153008'}, 'enable': 'true', 'created': '2025-08-11T11:46:58', 'last-modified': '2025-08-11T11:46:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.670983'}
2025-08-11 11:46:59,298 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:59,306 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16693340792008754966', 'uuid-lslong': '9902888877455153008'}, 'enable': 'true', 'created': '2025-08-11T11:46:58', 'last-modified': '2025-08-11T11:46:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.694195'}
2025-08-11 11:46:59,313 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:59,324 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16693340792008754966', 'uuid-lslong': '9902888877455153008'}, 'enable': 'true', 'created': '2025-08-11T11:46:58', 'last-modified': '2025-08-11T11:46:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.710618'}
2025-08-11 11:46:59,330 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:46:59,331 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-23117198 passed
2025-08-11 11:46:59,331 - DEBUG - ====Verifying policy data for service-left-vn-23117198 in API_Server ======
2025-08-11 11:46:59,331 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:46:59,345 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:46:59,363 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:46:59,438 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e7aaa901-5638-4716-896e-20f75ee19770
2025-08-11 11:46:59,448 - DEBUG - =>VN service-left-vn-23117198 has no policy to be verified
2025-08-11 11:46:59,448 - DEBUG - Verifying the vn in opserver
2025-08-11 11:46:59,448 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198 virtual network link through opserver 10.0.0.56
2025-08-11 11:46:59,448 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:46:59,534 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198?flat'}
2025-08-11 11:46:59,534 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198 virtual network link through opserver 10.0.0.38
2025-08-11 11:46:59,534 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:46:59,600 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198?flat'}
2025-08-11 11:46:59,600 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198 virtual network link through opserver 10.0.0.22
2025-08-11 11:46:59,600 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:46:59,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198?flat'}
2025-08-11 11:46:59,648 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:service-left-vn-23117198 is found in opserver
2025-08-11 11:46:59,670 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:46:59,679 - DEBUG - VRF ids for VN service-left-vn-23117198: {}
2025-08-11 11:46:59,899 - DEBUG - Response for create_network : {'network': {'id': 'edaae240-8aad-4c02-a8f1-4eedba706279', 'name': 'service-right-vn-81224235', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'service-right-vn-81224235'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:47:00,154 - DEBUG - Response for create_subnet : {'subnet': {'id': '615b747e-4ea3-4f5e-ac9c-ca3f281a5089', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': 'edaae240-8aad-4c02-a8f1-4eedba706279', 'ip_version': 4, 'cidr': '93.1.139.64/26', 'allocation_pools': [{'start': '93.1.139.66', 'end': '93.1.139.126'}], 'gateway_ip': '93.1.139.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '93.1.139.66', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:47:00,174 - INFO - Created VN service-right-vn-81224235
2025-08-11 11:47:00,186 - DEBUG - VN service-right-vn-81224235 UUID is edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,245 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:00,250 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:00,262 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:00,355 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,365 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,375 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:00,382 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:00,388 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/430e9e5a-cc38-49ce-8da6-2f03f1fd8c2a
2025-08-11 11:47:00,395 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:00,395 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,404 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:00,417 - INFO - Verified VN network id 13 for VN edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,417 - INFO - Verifications in API Server for VN service-right-vn-81224235 passed
2025-08-11 11:47:00,418 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,426 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:00,432 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:00,439 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/430e9e5a-cc38-49ce-8da6-2f03f1fd8c2a
2025-08-11 11:47:00,450 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17125749299862129666', 'uuid-lslong': '12173598050701304441'}, 'enable': 'true', 'created': '2025-08-11T11:46:59', 'last-modified': '2025-08-11T11:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.342954'}
2025-08-11 11:47:00,455 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:00,461 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17125749299862129666', 'uuid-lslong': '12173598050701304441'}, 'enable': 'true', 'created': '2025-08-11T11:46:59', 'last-modified': '2025-08-11T11:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.356231'}
2025-08-11 11:47:00,466 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:00,472 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17125749299862129666', 'uuid-lslong': '12173598050701304441'}, 'enable': 'true', 'created': '2025-08-11T11:46:59', 'last-modified': '2025-08-11T11:47:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367137'}
2025-08-11 11:47:00,478 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:00,479 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-81224235 passed
2025-08-11 11:47:00,479 - DEBUG - ====Verifying policy data for service-right-vn-81224235 in API_Server ======
2025-08-11 11:47:00,479 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:00,484 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:00,496 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:00,592 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/edaae240-8aad-4c02-a8f1-4eedba706279
2025-08-11 11:47:00,613 - DEBUG - =>VN service-right-vn-81224235 has no policy to be verified
2025-08-11 11:47:00,613 - DEBUG - Verifying the vn in opserver
2025-08-11 11:47:00,613 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235 virtual network link through opserver 10.0.0.56
2025-08-11 11:47:00,613 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:47:00,663 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235?flat'}
2025-08-11 11:47:00,663 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235 virtual network link through opserver 10.0.0.38
2025-08-11 11:47:00,663 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:47:00,716 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235?flat'}
2025-08-11 11:47:00,716 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235 virtual network link through opserver 10.0.0.22
2025-08-11 11:47:00,716 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:47:00,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235?flat'}
2025-08-11 11:47:00,766 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:service-right-vn-81224235 is found in opserver
2025-08-11 11:47:00,788 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:47:00,798 - DEBUG - VRF ids for VN service-right-vn-81224235: {}
2025-08-11 11:47:00,846 - DEBUG - Services list from nova: [, , , ]
2025-08-11 11:47:01,840 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-2)
2025-08-11 11:47:03,006 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-1)
2025-08-11 11:47:03,007 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-99966218']
2025-08-11 11:47:03,293 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-99966218'] created succesfully
2025-08-11 11:47:03,653 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 11:47:03,654 - DEBUG - Installing image tiny_nat_fw
2025-08-11 11:47:03,654 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 11:47:03,654 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:03,654 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:03,654 - DEBUG - Unzip image
2025-08-11 11:47:03,654 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:03,669 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 11:47:03,670 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-99966218']
2025-08-11 11:47:03,804 - INFO - Service template: ['default-domain', 'ctest-service_template_1-99966218'] deleted successfully.
2025-08-11 11:47:03,804 - INFO - Skip interface_detach for VM ctest-right_vm-25840553 in state BUILD
2025-08-11 11:47:03,804 - INFO - Deleting VM ctest-right_vm-25840553
2025-08-11 11:47:03,844 - ERROR - VM ctest-right_vm-25840553 has failed to come up
2025-08-11 11:47:03,844 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:03,844 - ERROR - VM ctest-right_vm-25840553 has failed to come up
2025-08-11 11:47:03,844 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:03,921 - INFO - Skip interface_detach for VM ctest-left_vm-53737398 in state BUILD
2025-08-11 11:47:03,922 - INFO - Deleting VM ctest-left_vm-53737398
2025-08-11 11:47:03,922 - ERROR - VM ctest-left_vm-53737398 has failed to come up
2025-08-11 11:47:03,922 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:03,922 - ERROR - VM ctest-left_vm-53737398 has failed to come up
2025-08-11 11:47:03,922 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:03,986 - INFO - Deleting VN service-right-vn-81224235
2025-08-11 11:47:04,026 - DEBUG - VN edaae240-8aad-4c02-a8f1-4eedba706279 still in use: Unable to complete operation on network edaae240-8aad-4c02-a8f1-4eedba706279. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-be9cea26-f8bc-4f22-907e-64e364bf3cda']
2025-08-11 11:47:04,026 - WARNING - Deleting VN service-right-vn-81224235 failed..Will retry
2025-08-11 11:47:06,159 - DEBUG - Response for deleting network ()
2025-08-11 11:47:06,160 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/068f74e6-e6a1-44f3-9bea-336aac489e73
2025-08-11 11:47:06,169 - DEBUG - Response Code: 404
2025-08-11 11:47:06,169 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:06,186 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:06,210 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:06,297 - INFO - Validated that VN service-right-vn-81224235 is not found in API Server
2025-08-11 11:47:06,308 - DEBUG - VN service-right-vn-81224235 is not present in Agent 10.0.0.19
2025-08-11 11:47:06,318 - DEBUG - VN service-right-vn-81224235 is not present in Agent 10.0.0.41
2025-08-11 11:47:06,318 - INFO - Validated that VN service-right-vn-81224235 is not in any agent
2025-08-11 11:47:06,347 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-81224235 info
2025-08-11 11:47:06,347 - INFO - Deleting VN service-left-vn-23117198
2025-08-11 11:47:06,477 - DEBUG - Response for deleting network ()
2025-08-11 11:47:06,477 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/c321b95a-84d2-4a51-9db0-d316ec75754b
2025-08-11 11:47:06,483 - DEBUG - Response Code: 404
2025-08-11 11:47:06,483 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:06,494 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:06,519 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:06,625 - INFO - Validated that VN service-left-vn-23117198 is not found in API Server
2025-08-11 11:47:06,636 - DEBUG - VN service-left-vn-23117198 is not present in Agent 10.0.0.19
2025-08-11 11:47:06,649 - DEBUG - VN service-left-vn-23117198 is not present in Agent 10.0.0.41
2025-08-11 11:47:06,650 - INFO - Validated that VN service-left-vn-23117198 is not in any agent
2025-08-11 11:47:06,687 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-23117198 info
2025-08-11 11:47:06,688 - INFO - Deleting VN ctest-mgmt_vn-19417767
2025-08-11 11:47:06,897 - DEBUG - Response for deleting network ()
2025-08-11 11:47:06,897 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/078da54e-0970-4ced-abad-7a1a07e139b5
2025-08-11 11:47:06,905 - DEBUG - Response Code: 404
2025-08-11 11:47:06,905 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:06,912 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:06,966 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:07,060 - INFO - Validated that VN ctest-mgmt_vn-19417767 is not found in API Server
2025-08-11 11:47:07,070 - DEBUG - VN ctest-mgmt_vn-19417767 is not present in Agent 10.0.0.19
2025-08-11 11:47:07,080 - DEBUG - VN ctest-mgmt_vn-19417767 is not present in Agent 10.0.0.41
2025-08-11 11:47:07,080 - INFO - Validated that VN ctest-mgmt_vn-19417767 is not in any agent
2025-08-11 11:47:07,109 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-19417767 info
2025-08-11 11:47:08,341 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 11:47:08,342 - INFO -
2025-08-11 11:47:08,342 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-08-11 11:47:08,342 - INFO - --------------------------------------------------------------------------------
2025-08-11 11:47:08,345 - INFO - ================================================================================
2025-08-11 11:47:08,345 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-11 11:47:09,576 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.41': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2025-08-11 11:47:09,576 - INFO - Initial checks done. Running the testcase now
2025-08-11 11:47:09,576 - INFO -
2025-08-11 11:47:09,790 - DEBUG - Response for create_network : {'network': {'id': '0f16c284-2b18-4007-80c7-97019599912d', 'name': 'ctest-mgmt_vn-41763062', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'ctest-mgmt_vn-41763062'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:47:09,982 - DEBUG - Response for create_subnet : {'subnet': {'id': '275c67d2-f0f3-4ddf-8bd7-ded6c8a73ab2', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': '0f16c284-2b18-4007-80c7-97019599912d', 'ip_version': 4, 'cidr': '88.131.68.0/26', 'allocation_pools': [{'start': '88.131.68.2', 'end': '88.131.68.62'}], 'gateway_ip': '88.131.68.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.131.68.2', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:47:10,001 - INFO - Created VN ctest-mgmt_vn-41763062
2025-08-11 11:47:10,013 - DEBUG - VN ctest-mgmt_vn-41763062 UUID is 0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,070 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:10,077 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:10,088 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:10,181 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,197 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,207 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:10,214 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:10,220 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8021f7c4-b255-4766-81a9-8efa9dfde4b9
2025-08-11 11:47:10,226 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:47:10,226 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,237 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:10,251 - INFO - Verified VN network id 11 for VN 0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,251 - INFO - Verifications in API Server for VN ctest-mgmt_vn-41763062 passed
2025-08-11 11:47:10,251 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,260 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:10,267 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:10,273 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/8021f7c4-b255-4766-81a9-8efa9dfde4b9
2025-08-11 11:47:10,284 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1087270232971034631', 'uuid-lslong': '9279551590280827181'}, 'enable': 'true', 'created': '2025-08-11T11:47:09', 'last-modified': '2025-08-11T11:47:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.348071'}
2025-08-11 11:47:10,290 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:47:10,296 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1087270232971034631', 'uuid-lslong': '9279551590280827181'}, 'enable': 'true', 'created': '2025-08-11T11:47:09', 'last-modified': '2025-08-11T11:47:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.360240'}
2025-08-11 11:47:10,301 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:47:10,307 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1087270232971034631', 'uuid-lslong': '9279551590280827181'}, 'enable': 'true', 'created': '2025-08-11T11:47:09', 'last-modified': '2025-08-11T11:47:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.371515'}
2025-08-11 11:47:10,312 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 11:47:10,312 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-41763062 passed
2025-08-11 11:47:10,313 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-41763062 in API_Server ======
2025-08-11 11:47:10,313 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:10,318 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:10,329 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:10,411 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0f16c284-2b18-4007-80c7-97019599912d
2025-08-11 11:47:10,423 - DEBUG - =>VN ctest-mgmt_vn-41763062 has no policy to be verified
2025-08-11 11:47:10,423 - DEBUG - Verifying the vn in opserver
2025-08-11 11:47:10,423 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062 virtual network link through opserver 10.0.0.56
2025-08-11 11:47:10,423 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:47:10,434 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062?flat'}
2025-08-11 11:47:10,434 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062 virtual network link through opserver 10.0.0.38
2025-08-11 11:47:10,434 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:47:10,445 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062?flat'}
2025-08-11 11:47:10,445 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062 virtual network link through opserver 10.0.0.22
2025-08-11 11:47:10,445 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:47:10,456 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062?flat'}
2025-08-11 11:47:10,457 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:ctest-mgmt_vn-41763062 is found in opserver
2025-08-11 11:47:10,478 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:47:10,489 - DEBUG - VRF ids for VN ctest-mgmt_vn-41763062: {}
2025-08-11 11:47:10,776 - DEBUG - Response for create_network : {'network': {'id': 'f0a33073-d357-46db-b5c9-93be5edea906', 'name': 'ctest-left_vn-65485855', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'ctest-left_vn-65485855'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:47:11,025 - DEBUG - Response for create_subnet : {'subnet': {'id': '7aa657eb-0ee7-4871-aeb0-8ae572426b19', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': 'f0a33073-d357-46db-b5c9-93be5edea906', 'ip_version': 4, 'cidr': '142.33.148.64/26', 'allocation_pools': [{'start': '142.33.148.66', 'end': '142.33.148.126'}], 'gateway_ip': '142.33.148.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.33.148.66', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:47:11,044 - INFO - Created VN ctest-left_vn-65485855
2025-08-11 11:47:11,054 - DEBUG - VN ctest-left_vn-65485855 UUID is f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,112 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:11,119 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:11,130 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:11,212 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,226 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,235 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:11,243 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:11,250 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/69912691-3415-495f-8763-cff373afe4ec
2025-08-11 11:47:11,256 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:47:11,256 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,264 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:11,278 - INFO - Verified VN network id 12 for VN f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,278 - INFO - Verifications in API Server for VN ctest-left_vn-65485855 passed
2025-08-11 11:47:11,278 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,286 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:11,293 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:11,299 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/69912691-3415-495f-8763-cff373afe4ec
2025-08-11 11:47:11,311 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17339756264331626203', 'uuid-lslong': '13099163437028518150'}, 'enable': 'true', 'created': '2025-08-11T11:47:10', 'last-modified': '2025-08-11T11:47:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.350627'}
2025-08-11 11:47:11,316 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:47:11,322 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17339756264331626203', 'uuid-lslong': '13099163437028518150'}, 'enable': 'true', 'created': '2025-08-11T11:47:10', 'last-modified': '2025-08-11T11:47:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.364544'}
2025-08-11 11:47:11,328 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:47:11,334 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17339756264331626203', 'uuid-lslong': '13099163437028518150'}, 'enable': 'true', 'created': '2025-08-11T11:47:10', 'last-modified': '2025-08-11T11:47:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369993'}
2025-08-11 11:47:11,340 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 11:47:11,340 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-65485855 passed
2025-08-11 11:47:11,340 - DEBUG - ====Verifying policy data for ctest-left_vn-65485855 in API_Server ======
2025-08-11 11:47:11,340 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:11,345 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:11,356 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:11,450 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f0a33073-d357-46db-b5c9-93be5edea906
2025-08-11 11:47:11,459 - DEBUG - =>VN ctest-left_vn-65485855 has no policy to be verified
2025-08-11 11:47:11,459 - DEBUG - Verifying the vn in opserver
2025-08-11 11:47:11,459 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855 virtual network link through opserver 10.0.0.56
2025-08-11 11:47:11,459 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:47:11,472 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855?flat'}
2025-08-11 11:47:11,472 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855 virtual network link through opserver 10.0.0.38
2025-08-11 11:47:11,472 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:47:11,482 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855?flat'}
2025-08-11 11:47:11,483 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855 virtual network link through opserver 10.0.0.22
2025-08-11 11:47:11,483 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:47:11,494 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855?flat'}
2025-08-11 11:47:11,494 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:ctest-left_vn-65485855 is found in opserver
2025-08-11 11:47:11,515 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:47:11,527 - DEBUG - VRF ids for VN ctest-left_vn-65485855: {}
2025-08-11 11:47:11,749 - DEBUG - Response for create_network : {'network': {'id': 'bd805dce-3e4f-45e2-b615-6e49ac8f3804', 'name': 'ctest-right_vn-31694851', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'project_id': '1f86fd18c38a426fa88c4b08e65abcec', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-96759272', 'ctest-right_vn-31694851'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 11:47:11,992 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ef138ed2-ed80-430b-a4ed-1a4b414e5250', 'name': '', 'tenant_id': '1f86fd18c38a426fa88c4b08e65abcec', 'network_id': 'bd805dce-3e4f-45e2-b615-6e49ac8f3804', 'ip_version': 4, 'cidr': '59.108.107.64/26', 'allocation_pools': [{'start': '59.108.107.66', 'end': '59.108.107.126'}], 'gateway_ip': '59.108.107.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.108.107.66', 'tags': [], 'project_id': '1f86fd18c38a426fa88c4b08e65abcec'}}
2025-08-11 11:47:12,012 - INFO - Created VN ctest-right_vn-31694851
2025-08-11 11:47:12,022 - DEBUG - VN ctest-right_vn-31694851 UUID is bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,090 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:12,096 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:12,106 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:12,189 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,200 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,208 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:12,216 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:12,222 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/d4513fd2-1ac4-4c29-aafa-de8728a671fe
2025-08-11 11:47:12,228 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:12,228 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,236 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:12,250 - INFO - Verified VN network id 13 for VN bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,250 - INFO - Verifications in API Server for VN ctest-right_vn-31694851 passed
2025-08-11 11:47:12,250 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,258 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:12,265 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:12,271 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/d4513fd2-1ac4-4c29-aafa-de8728a671fe
2025-08-11 11:47:12,293 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13655017210577372642', 'uuid-lslong': '13120514352120543236'}, 'enable': 'true', 'created': '2025-08-11T11:47:11', 'last-modified': '2025-08-11T11:47:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.330804'}
2025-08-11 11:47:12,301 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:12,307 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13655017210577372642', 'uuid-lslong': '13120514352120543236'}, 'enable': 'true', 'created': '2025-08-11T11:47:11', 'last-modified': '2025-08-11T11:47:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.355397'}
2025-08-11 11:47:12,314 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:12,322 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-96759272', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13655017210577372642', 'uuid-lslong': '13120514352120543236'}, 'enable': 'true', 'created': '2025-08-11T11:47:11', 'last-modified': '2025-08-11T11:47:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362498'}
2025-08-11 11:47:12,333 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 11:47:12,333 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-31694851 passed
2025-08-11 11:47:12,333 - DEBUG - ====Verifying policy data for ctest-right_vn-31694851 in API_Server ======
2025-08-11 11:47:12,333 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:12,345 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:12,357 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:12,440 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/bd805dce-3e4f-45e2-b615-6e49ac8f3804
2025-08-11 11:47:12,453 - DEBUG - =>VN ctest-right_vn-31694851 has no policy to be verified
2025-08-11 11:47:12,454 - DEBUG - Verifying the vn in opserver
2025-08-11 11:47:12,454 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851 virtual network link through opserver 10.0.0.56
2025-08-11 11:47:12,454 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-11 11:47:12,465 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851?flat'}
2025-08-11 11:47:12,465 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851 virtual network link through opserver 10.0.0.38
2025-08-11 11:47:12,465 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 11:47:12,476 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851?flat'}
2025-08-11 11:47:12,476 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851 virtual network link through opserver 10.0.0.22
2025-08-11 11:47:12,477 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2025-08-11 11:47:12,488 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851?flat'}
2025-08-11 11:47:12,489 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-96759272:ctest-right_vn-31694851 is found in opserver
2025-08-11 11:47:12,509 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 11:47:12,518 - DEBUG - VRF ids for VN ctest-right_vn-31694851: {}
2025-08-11 11:47:13,473 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-1)
2025-08-11 11:47:14,675 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3803-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3803-2)
2025-08-11 11:47:14,675 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-41106511']
2025-08-11 11:47:14,961 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-41106511'] created succesfully
2025-08-11 11:47:15,265 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 11:47:15,265 - DEBUG - Installing image tiny_nat_fw
2025-08-11 11:47:15,265 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 11:47:15,265 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:15,265 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:15,265 - DEBUG - Unzip image
2025-08-11 11:47:15,266 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 11:47:15,281 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 11:47:15,282 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-41106511']
2025-08-11 11:47:15,408 - INFO - Service template: ['default-domain', 'ctest-service_template_1-41106511'] deleted successfully.
2025-08-11 11:47:15,408 - INFO - Skip interface_detach for VM ctest-right_vm-51708971 in state BUILD
2025-08-11 11:47:15,408 - INFO - Deleting VM ctest-right_vm-51708971
2025-08-11 11:47:15,408 - ERROR - VM ctest-right_vm-51708971 has failed to come up
2025-08-11 11:47:15,408 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:15,408 - ERROR - VM ctest-right_vm-51708971 has failed to come up
2025-08-11 11:47:15,408 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:15,472 - INFO - Skip interface_detach for VM ctest-left_vm-57471406 in state BUILD
2025-08-11 11:47:15,472 - INFO - Deleting VM ctest-left_vm-57471406
2025-08-11 11:47:15,472 - ERROR - VM ctest-left_vm-57471406 has failed to come up
2025-08-11 11:47:15,472 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:15,472 - ERROR - VM ctest-left_vm-57471406 has failed to come up
2025-08-11 11:47:15,472 - ERROR - Nova failed to get host of the VM
2025-08-11 11:47:15,532 - INFO - Deleting VN ctest-right_vn-31694851
2025-08-11 11:47:15,574 - DEBUG - VN bd805dce-3e4f-45e2-b615-6e49ac8f3804 still in use: Unable to complete operation on network bd805dce-3e4f-45e2-b615-6e49ac8f3804. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ed9b3bd6-86bd-4586-bb33-7b22b125a31e']
2025-08-11 11:47:15,574 - WARNING - Deleting VN ctest-right_vn-31694851 failed..Will retry
2025-08-11 11:47:17,711 - DEBUG - Response for deleting network ()
2025-08-11 11:47:17,712 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0e3a8c47-ea9c-4014-985b-19008b377489
2025-08-11 11:47:17,717 - DEBUG - Response Code: 404
2025-08-11 11:47:17,718 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:17,722 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:17,751 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:17,846 - INFO - Validated that VN ctest-right_vn-31694851 is not found in API Server
2025-08-11 11:47:17,857 - DEBUG - VN ctest-right_vn-31694851 is not present in Agent 10.0.0.19
2025-08-11 11:47:17,867 - DEBUG - VN ctest-right_vn-31694851 is not present in Agent 10.0.0.41
2025-08-11 11:47:17,867 - INFO - Validated that VN ctest-right_vn-31694851 is not in any agent
2025-08-11 11:47:17,896 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-31694851 info
2025-08-11 11:47:17,896 - INFO - Deleting VN ctest-left_vn-65485855
2025-08-11 11:47:18,035 - DEBUG - Response for deleting network ()
2025-08-11 11:47:18,036 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/24a93ba0-18dc-4260-9638-364252066ad7
2025-08-11 11:47:18,050 - DEBUG - Response Code: 404
2025-08-11 11:47:18,050 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:18,056 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:18,109 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:18,194 - INFO - Validated that VN ctest-left_vn-65485855 is not found in API Server
2025-08-11 11:47:18,206 - DEBUG - VN ctest-left_vn-65485855 is not present in Agent 10.0.0.19
2025-08-11 11:47:18,216 - DEBUG - VN ctest-left_vn-65485855 is not present in Agent 10.0.0.41
2025-08-11 11:47:18,216 - INFO - Validated that VN ctest-left_vn-65485855 is not in any agent
2025-08-11 11:47:18,245 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-65485855 info
2025-08-11 11:47:18,245 - INFO - Deleting VN ctest-mgmt_vn-41763062
2025-08-11 11:47:18,379 - DEBUG - Response for deleting network ()
2025-08-11 11:47:18,379 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ba7fcdb0-5d47-421e-9a40-81a39d1ee97a
2025-08-11 11:47:18,387 - DEBUG - Response Code: 404
2025-08-11 11:47:18,387 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-11 11:47:18,397 - DEBUG - Requesting: http://10.0.0.56:8082/domain/b5486b30-eba1-4fa6-8a8f-46cb32823c52
2025-08-11 11:47:18,411 - DEBUG - Requesting: http://10.0.0.56:8082/project/1f86fd18-c38a-426f-a88c-4b08e65abcec
2025-08-11 11:47:18,503 - INFO - Validated that VN ctest-mgmt_vn-41763062 is not found in API Server
2025-08-11 11:47:18,515 - DEBUG - VN ctest-mgmt_vn-41763062 is not present in Agent 10.0.0.19
2025-08-11 11:47:18,526 - DEBUG - VN ctest-mgmt_vn-41763062 is not present in Agent 10.0.0.41
2025-08-11 11:47:18,526 - INFO - Validated that VN ctest-mgmt_vn-41763062 is not in any agent
2025-08-11 11:47:18,556 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-41763062 info
2025-08-11 11:47:19,791 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 11:47:19,792 - INFO -
2025-08-11 11:47:19,792 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:11]
2025-08-11 11:47:19,792 - INFO - --------------------------------------------------------------------------------
2025-08-11 11:47:20,502 - INFO - Deleted project: ctest-TestSvcRegr-96759272, ID : 1f86fd18-c38a-426f-a88c-4b08e65abcec