]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:13:28,574 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-91181217']
2025-08-07 03:13:28,887 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-91181217'] created succesfully
2025-08-07 03:13:29,276 - DEBUG - Image by name tiny_nat_fw not found
2025-08-07 03:13:29,276 - DEBUG - Installing image tiny_nat_fw
2025-08-07 03:13:29,276 - 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-07 03:13:29,277 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-07 03:13:29,277 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-07 03:13:29,327 - 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-07 03:13:29,398 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:13:29,398 - DEBUG - Unzip image
2025-08-07 03:13:29,398 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:13:29,433 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-07 03:13:29,433 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-91181217']
2025-08-07 03:13:29,534 - INFO - Service template: ['default-domain', 'ctest-service_template_1-91181217'] deleted successfully.
2025-08-07 03:13:29,534 - INFO - Skip interface_detach for VM ctest-right_vm-45429282 in state BUILD
2025-08-07 03:13:29,534 - INFO - Deleting VM ctest-right_vm-45429282
2025-08-07 03:13:29,567 - ERROR - VM ctest-right_vm-45429282 has failed to come up
2025-08-07 03:13:29,567 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:29,567 - ERROR - VM ctest-right_vm-45429282 has failed to come up
2025-08-07 03:13:29,567 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:29,692 - INFO - Skip interface_detach for VM ctest-left_vm-81963431 in state BUILD
2025-08-07 03:13:29,692 - INFO - Deleting VM ctest-left_vm-81963431
2025-08-07 03:13:29,692 - ERROR - VM ctest-left_vm-81963431 has failed to come up
2025-08-07 03:13:29,692 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:29,692 - ERROR - VM ctest-left_vm-81963431 has failed to come up
2025-08-07 03:13:29,692 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:29,784 - INFO - Deleting VN ctest-right_vn-71641151
2025-08-07 03:13:29,852 - DEBUG - VN 7563ee47-45da-45e2-adfa-dacdc7252e28 still in use: Unable to complete operation on network 7563ee47-45da-45e2-adfa-dacdc7252e28. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-611142d7-77c0-4f42-83be-4ad442994a61']
2025-08-07 03:13:29,853 - WARNING - Deleting VN ctest-right_vn-71641151 failed..Will retry
2025-08-07 03:13:32,045 - DEBUG - Response for deleting network ()
2025-08-07 03:13:32,045 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/9e2e511e-7c25-4ddf-90e2-d604fd38bce7
2025-08-07 03:13:32,055 - DEBUG - Response Code: 404
2025-08-07 03:13:32,055 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:32,073 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:32,097 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:32,238 - INFO - Validated that VN ctest-right_vn-71641151 is not found in API Server
2025-08-07 03:13:32,253 - DEBUG - VN ctest-right_vn-71641151 is not present in Agent 10.0.0.41
2025-08-07 03:13:32,270 - DEBUG - VN ctest-right_vn-71641151 is not present in Agent 10.0.0.61
2025-08-07 03:13:32,270 - INFO - Validated that VN ctest-right_vn-71641151 is not in any agent
2025-08-07 03:13:32,318 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-71641151 info
2025-08-07 03:13:32,318 - INFO - Deleting VN ctest-left_vn-99322019
2025-08-07 03:13:32,515 - DEBUG - Response for deleting network ()
2025-08-07 03:13:32,515 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/292efb00-dc8f-4b6d-9382-e8c19524b228
2025-08-07 03:13:32,524 - DEBUG - Response Code: 404
2025-08-07 03:13:32,524 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:32,531 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:32,548 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:32,656 - INFO - Validated that VN ctest-left_vn-99322019 is not found in API Server
2025-08-07 03:13:32,671 - DEBUG - VN ctest-left_vn-99322019 is not present in Agent 10.0.0.41
2025-08-07 03:13:32,691 - DEBUG - VN ctest-left_vn-99322019 is not present in Agent 10.0.0.61
2025-08-07 03:13:32,691 - INFO - Validated that VN ctest-left_vn-99322019 is not in any agent
2025-08-07 03:13:32,735 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-99322019 info
2025-08-07 03:13:32,736 - INFO - Deleting VN ctest-mgmt_vn-59382630
2025-08-07 03:13:32,909 - DEBUG - Response for deleting network ()
2025-08-07 03:13:32,910 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0a409786-7df3-4c82-b801-46f3d990111c
2025-08-07 03:13:32,917 - DEBUG - Response Code: 404
2025-08-07 03:13:32,917 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:32,925 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:32,954 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:33,076 - INFO - Validated that VN ctest-mgmt_vn-59382630 is not found in API Server
2025-08-07 03:13:33,092 - DEBUG - VN ctest-mgmt_vn-59382630 is not present in Agent 10.0.0.41
2025-08-07 03:13:33,109 - DEBUG - VN ctest-mgmt_vn-59382630 is not present in Agent 10.0.0.61
2025-08-07 03:13:33,109 - INFO - Validated that VN ctest-mgmt_vn-59382630 is not in any agent
2025-08-07 03:13:33,160 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-59382630 info
2025-08-07 03:13:33,160 - INFO - Deleting VM ctest-vm-in-third-vn-85007979
2025-08-07 03:13:33,241 - INFO - Deleting VN ctest-third-vn-97929634
2025-08-07 03:13:33,297 - DEBUG - VN 8fbbdcce-d90e-485e-877d-0c603f5bc58c still in use: Unable to complete operation on network 8fbbdcce-d90e-485e-877d-0c603f5bc58c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5efc2a92-8e64-460e-9f7d-bb6f3409ec73']
2025-08-07 03:13:33,297 - WARNING - Deleting VN ctest-third-vn-97929634 failed..Will retry
2025-08-07 03:13:35,496 - DEBUG - Response for deleting network ()
2025-08-07 03:13:36,777 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 03:13:36,777 - INFO -
2025-08-07 03:13:36,777 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:26]
2025-08-07 03:13:36,777 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:13:36,780 - INFO - ================================================================================
2025-08-07 03:13:36,780 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-07 03:13:38,078 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.61': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2025-08-07 03:13:38,078 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:13:38,079 - INFO -
2025-08-07 03:13:38,399 - DEBUG - Response for create_network : {'network': {'id': 'b328b668-155b-4a26-840a-bf3a536898de', 'name': 'ctest-mgmt_vn-31561516', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87662300', 'ctest-mgmt_vn-31561516'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:38,646 - DEBUG - Response for create_subnet : {'subnet': {'id': '273fd665-3ee6-44c6-b9e0-0bedf0ef0058', 'name': '', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'network_id': 'b328b668-155b-4a26-840a-bf3a536898de', 'ip_version': 4, 'cidr': '206.172.49.192/26', 'allocation_pools': [{'start': '206.172.49.194', 'end': '206.172.49.254'}], 'gateway_ip': '206.172.49.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '206.172.49.194', 'tags': [], 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e'}}
2025-08-07 03:13:38,670 - INFO - Created VN ctest-mgmt_vn-31561516
2025-08-07 03:13:38,686 - DEBUG - VN ctest-mgmt_vn-31561516 UUID is b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:38,789 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:38,800 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:38,815 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:38,918 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:38,934 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:38,948 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:38,959 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:38,969 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/62e9549c-1b3c-42ad-a060-afa9f03e99fe
2025-08-07 03:13:38,978 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:13:38,978 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:38,989 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:39,011 - INFO - Verified VN network id 12 for VN b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:39,012 - INFO - Verifications in API Server for VN ctest-mgmt_vn-31561516 passed
2025-08-07 03:13:39,013 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:39,024 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:39,033 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:39,044 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/62e9549c-1b3c-42ad-a060-afa9f03e99fe
2025-08-07 03:13:39,058 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12909768890008685094', 'uuid-lslong': '9514627420001966302'}, 'enable': 'true', 'created': '2025-08-07T03:13:38', 'last-modified': '2025-08-07T03:13:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.474040'}
2025-08-07 03:13:39,065 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:13:39,074 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12909768890008685094', 'uuid-lslong': '9514627420001966302'}, 'enable': 'true', 'created': '2025-08-07T03:13:38', 'last-modified': '2025-08-07T03:13:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.490020'}
2025-08-07 03:13:39,082 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:13:39,089 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12909768890008685094', 'uuid-lslong': '9514627420001966302'}, 'enable': 'true', 'created': '2025-08-07T03:13:38', 'last-modified': '2025-08-07T03:13:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512675'}
2025-08-07 03:13:39,098 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:13:39,098 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-31561516 passed
2025-08-07 03:13:39,099 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-31561516 in API_Server ======
2025-08-07 03:13:39,099 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:39,106 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:39,120 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:39,226 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b328b668-155b-4a26-840a-bf3a536898de
2025-08-07 03:13:39,243 - DEBUG - =>VN ctest-mgmt_vn-31561516 has no policy to be verified
2025-08-07 03:13:39,243 - DEBUG - Verifying the vn in opserver
2025-08-07 03:13:39,243 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516 virtual network link through opserver 10.0.0.56
2025-08-07 03:13:39,243 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:13:39,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516?flat'}
2025-08-07 03:13:39,262 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516 virtual network link through opserver 10.0.0.130
2025-08-07 03:13:39,262 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:13:39,279 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516?flat'}
2025-08-07 03:13:39,279 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516 virtual network link through opserver 10.0.0.249
2025-08-07 03:13:39,279 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:13:39,296 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516?flat'}
2025-08-07 03:13:39,296 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87662300:ctest-mgmt_vn-31561516 is found in opserver
2025-08-07 03:13:39,325 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:13:39,338 - DEBUG - VRF ids for VN ctest-mgmt_vn-31561516: {}
2025-08-07 03:13:39,660 - DEBUG - Response for create_network : {'network': {'id': '5de2f3f9-289d-428e-84c9-6cbc3c27358c', 'name': 'ctest-left_vn-21835192', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87662300', 'ctest-left_vn-21835192'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:39,950 - DEBUG - Response for create_subnet : {'subnet': {'id': '065c2097-8123-42f8-94a4-7a718f187236', 'name': '', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'network_id': '5de2f3f9-289d-428e-84c9-6cbc3c27358c', 'ip_version': 4, 'cidr': '27.31.172.128/26', 'allocation_pools': [{'start': '27.31.172.130', 'end': '27.31.172.190'}], 'gateway_ip': '27.31.172.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.31.172.130', 'tags': [], 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e'}}
2025-08-07 03:13:39,974 - INFO - Created VN ctest-left_vn-21835192
2025-08-07 03:13:39,990 - DEBUG - VN ctest-left_vn-21835192 UUID is 5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,084 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:40,092 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:40,107 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:40,209 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,223 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,235 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:40,244 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:40,255 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/f7d9943b-5598-45ec-b4e4-c5d6f5f90589
2025-08-07 03:13:40,268 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:13:40,268 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,280 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:40,302 - INFO - Verified VN network id 13 for VN 5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,302 - INFO - Verifications in API Server for VN ctest-left_vn-21835192 passed
2025-08-07 03:13:40,302 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,315 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:40,325 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:40,335 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/f7d9943b-5598-45ec-b4e4-c5d6f5f90589
2025-08-07 03:13:40,353 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6765237841717707406', 'uuid-lslong': '9568298439044183436'}, 'enable': 'true', 'created': '2025-08-07T03:13:39', 'last-modified': '2025-08-07T03:13:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.477981'}
2025-08-07 03:13:40,363 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:13:40,370 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6765237841717707406', 'uuid-lslong': '9568298439044183436'}, 'enable': 'true', 'created': '2025-08-07T03:13:39', 'last-modified': '2025-08-07T03:13:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.495163'}
2025-08-07 03:13:40,379 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:13:40,388 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6765237841717707406', 'uuid-lslong': '9568298439044183436'}, 'enable': 'true', 'created': '2025-08-07T03:13:39', 'last-modified': '2025-08-07T03:13:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.511677'}
2025-08-07 03:13:40,396 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:13:40,396 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-21835192 passed
2025-08-07 03:13:40,396 - DEBUG - ====Verifying policy data for ctest-left_vn-21835192 in API_Server ======
2025-08-07 03:13:40,397 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:40,405 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:40,419 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:40,530 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5de2f3f9-289d-428e-84c9-6cbc3c27358c
2025-08-07 03:13:40,543 - DEBUG - =>VN ctest-left_vn-21835192 has no policy to be verified
2025-08-07 03:13:40,543 - DEBUG - Verifying the vn in opserver
2025-08-07 03:13:40,543 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192 virtual network link through opserver 10.0.0.56
2025-08-07 03:13:40,543 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:13:40,559 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192?flat'}
2025-08-07 03:13:40,559 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192 virtual network link through opserver 10.0.0.130
2025-08-07 03:13:40,559 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:13:40,578 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192?flat'}
2025-08-07 03:13:40,578 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192 virtual network link through opserver 10.0.0.249
2025-08-07 03:13:40,579 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:13:40,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192?flat'}
2025-08-07 03:13:40,601 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87662300:ctest-left_vn-21835192 is found in opserver
2025-08-07 03:13:40,630 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:13:40,644 - DEBUG - VRF ids for VN ctest-left_vn-21835192: {}
2025-08-07 03:13:40,941 - DEBUG - Response for create_network : {'network': {'id': '91da2ac0-f815-4888-afa2-b0e3e6a748af', 'name': 'ctest-right_vn-76905340', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87662300', 'ctest-right_vn-76905340'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:41,250 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bde78ae9-bfb2-4db4-baaa-8c4cec591d76', 'name': '', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'network_id': '91da2ac0-f815-4888-afa2-b0e3e6a748af', 'ip_version': 4, 'cidr': '53.204.71.64/26', 'allocation_pools': [{'start': '53.204.71.66', 'end': '53.204.71.126'}], 'gateway_ip': '53.204.71.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.204.71.66', 'tags': [], 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e'}}
2025-08-07 03:13:41,277 - INFO - Created VN ctest-right_vn-76905340
2025-08-07 03:13:41,292 - DEBUG - VN ctest-right_vn-76905340 UUID is 91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,382 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:41,391 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:41,405 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:41,523 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,546 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,558 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:41,570 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:41,580 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/342d8bd8-f123-4c74-909a-e742a4a79292
2025-08-07 03:13:41,590 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:13:41,590 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,601 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:41,622 - INFO - Verified VN network id 14 for VN 91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,622 - INFO - Verifications in API Server for VN ctest-right_vn-76905340 passed
2025-08-07 03:13:41,622 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,637 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:41,647 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:41,656 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/342d8bd8-f123-4c74-909a-e742a4a79292
2025-08-07 03:13:41,673 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10509759688706705544', 'uuid-lslong': '12655872395738302639'}, 'enable': 'true', 'created': '2025-08-07T03:13:40', 'last-modified': '2025-08-07T03:13:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500583'}
2025-08-07 03:13:41,680 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:13:41,687 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10509759688706705544', 'uuid-lslong': '12655872395738302639'}, 'enable': 'true', 'created': '2025-08-07T03:13:40', 'last-modified': '2025-08-07T03:13:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.514888'}
2025-08-07 03:13:41,696 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:13:41,703 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10509759688706705544', 'uuid-lslong': '12655872395738302639'}, 'enable': 'true', 'created': '2025-08-07T03:13:40', 'last-modified': '2025-08-07T03:13:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.536353'}
2025-08-07 03:13:41,710 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:13:41,710 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-76905340 passed
2025-08-07 03:13:41,710 - DEBUG - ====Verifying policy data for ctest-right_vn-76905340 in API_Server ======
2025-08-07 03:13:41,711 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:41,718 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:41,733 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:41,843 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/91da2ac0-f815-4888-afa2-b0e3e6a748af
2025-08-07 03:13:41,862 - DEBUG - =>VN ctest-right_vn-76905340 has no policy to be verified
2025-08-07 03:13:41,862 - DEBUG - Verifying the vn in opserver
2025-08-07 03:13:41,862 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340 virtual network link through opserver 10.0.0.56
2025-08-07 03:13:41,862 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:13:41,880 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340?flat'}
2025-08-07 03:13:41,880 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340 virtual network link through opserver 10.0.0.130
2025-08-07 03:13:41,880 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:13:41,897 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340?flat'}
2025-08-07 03:13:41,897 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340 virtual network link through opserver 10.0.0.249
2025-08-07 03:13:41,897 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:13:41,913 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340?flat'}
2025-08-07 03:13:41,913 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87662300:ctest-right_vn-76905340 is found in opserver
2025-08-07 03:13:41,939 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:13:41,953 - DEBUG - VRF ids for VN ctest-right_vn-76905340: {}
2025-08-07 03:13:42,262 - DEBUG - Response for create_network : {'network': {'id': '5481d479-f9dd-4425-9f13-4cc8bab9c72d', 'name': 'ctest-trans_left_vn-47884456', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87662300', 'ctest-trans_left_vn-47884456'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:42,554 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e418b94a-51b4-4b5f-9ce7-90aa0d899dfc', 'name': '', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'network_id': '5481d479-f9dd-4425-9f13-4cc8bab9c72d', 'ip_version': 4, 'cidr': '89.91.217.192/26', 'allocation_pools': [{'start': '89.91.217.194', 'end': '89.91.217.254'}], 'gateway_ip': '89.91.217.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.91.217.194', 'tags': [], 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e'}}
2025-08-07 03:13:42,580 - INFO - Created VN ctest-trans_left_vn-47884456
2025-08-07 03:13:42,599 - DEBUG - VN ctest-trans_left_vn-47884456 UUID is 5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,683 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:42,691 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:42,706 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:42,815 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,830 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,840 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:42,849 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:42,859 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/39c4c7a1-f2cb-4a10-abb2-e9683ce768ed
2025-08-07 03:13:42,866 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-07 03:13:42,866 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,878 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:42,897 - INFO - Verified VN network id 15 for VN 5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,897 - INFO - Verifications in API Server for VN ctest-trans_left_vn-47884456 passed
2025-08-07 03:13:42,897 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:42,909 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:42,918 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:42,926 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/39c4c7a1-f2cb-4a10-abb2-e9683ce768ed
2025-08-07 03:13:42,940 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6089381791529780261', 'uuid-lslong': '11462589901597951789'}, 'enable': 'true', 'created': '2025-08-07T03:13:42', 'last-modified': '2025-08-07T03:13:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449812'}
2025-08-07 03:13:42,947 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-07 03:13:42,954 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6089381791529780261', 'uuid-lslong': '11462589901597951789'}, 'enable': 'true', 'created': '2025-08-07T03:13:42', 'last-modified': '2025-08-07T03:13:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465737'}
2025-08-07 03:13:42,962 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-07 03:13:42,970 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6089381791529780261', 'uuid-lslong': '11462589901597951789'}, 'enable': 'true', 'created': '2025-08-07T03:13:42', 'last-modified': '2025-08-07T03:13:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483898'}
2025-08-07 03:13:42,978 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-07 03:13:42,978 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-47884456 passed
2025-08-07 03:13:42,978 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-47884456 in API_Server ======
2025-08-07 03:13:42,978 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:42,987 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:43,002 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:43,108 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/5481d479-f9dd-4425-9f13-4cc8bab9c72d
2025-08-07 03:13:43,122 - DEBUG - =>VN ctest-trans_left_vn-47884456 has no policy to be verified
2025-08-07 03:13:43,122 - DEBUG - Verifying the vn in opserver
2025-08-07 03:13:43,122 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456 virtual network link through opserver 10.0.0.56
2025-08-07 03:13:43,122 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:13:43,139 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456?flat'}
2025-08-07 03:13:43,139 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456 virtual network link through opserver 10.0.0.130
2025-08-07 03:13:43,139 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:13:43,157 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456?flat'}
2025-08-07 03:13:43,157 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456 virtual network link through opserver 10.0.0.249
2025-08-07 03:13:43,157 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:13:43,173 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456?flat'}
2025-08-07 03:13:43,173 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87662300:ctest-trans_left_vn-47884456 is found in opserver
2025-08-07 03:13:43,200 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:13:43,214 - DEBUG - VRF ids for VN ctest-trans_left_vn-47884456: {}
2025-08-07 03:13:43,528 - DEBUG - Response for create_network : {'network': {'id': 'b85c7c89-faa2-40b9-a9fa-a6c36788bead', 'name': 'ctest-trans_right_vn-13910358', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87662300', 'ctest-trans_right_vn-13910358'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:43,915 - DEBUG - Response for create_subnet : {'subnet': {'id': '55d55e29-d357-4080-bfdb-c387a5107a17', 'name': '', 'tenant_id': '11216b87c86f4e4c9a42ae6e6ab5794e', 'network_id': 'b85c7c89-faa2-40b9-a9fa-a6c36788bead', 'ip_version': 4, 'cidr': '173.33.8.0/26', 'allocation_pools': [{'start': '173.33.8.2', 'end': '173.33.8.62'}], 'gateway_ip': '173.33.8.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.33.8.2', 'tags': [], 'project_id': '11216b87c86f4e4c9a42ae6e6ab5794e'}}
2025-08-07 03:13:43,943 - INFO - Created VN ctest-trans_right_vn-13910358
2025-08-07 03:13:43,962 - DEBUG - VN ctest-trans_right_vn-13910358 UUID is b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,050 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:44,060 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:44,075 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:44,200 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,216 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,233 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:44,242 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:44,251 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/a60e91ec-f5f3-4084-a489-aab6c12efeb8
2025-08-07 03:13:44,262 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-07 03:13:44,262 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,272 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:44,292 - INFO - Verified VN network id 16 for VN b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,292 - INFO - Verifications in API Server for VN ctest-trans_right_vn-13910358 passed
2025-08-07 03:13:44,292 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,307 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:44,317 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:44,326 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/a60e91ec-f5f3-4084-a489-aab6c12efeb8
2025-08-07 03:13:44,341 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13284629932893421753', 'uuid-lslong': '12248285494773333677'}, 'enable': 'true', 'created': '2025-08-07T03:13:43', 'last-modified': '2025-08-07T03:13:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500600'}
2025-08-07 03:13:44,350 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-07 03:13:44,361 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13284629932893421753', 'uuid-lslong': '12248285494773333677'}, 'enable': 'true', 'created': '2025-08-07T03:13:43', 'last-modified': '2025-08-07T03:13:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.521309'}
2025-08-07 03:13:44,372 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-07 03:13:44,379 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87662300', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13284629932893421753', 'uuid-lslong': '12248285494773333677'}, 'enable': 'true', 'created': '2025-08-07T03:13:43', 'last-modified': '2025-08-07T03:13:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534953'}
2025-08-07 03:13:44,387 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-07 03:13:44,387 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-13910358 passed
2025-08-07 03:13:44,387 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-13910358 in API_Server ======
2025-08-07 03:13:44,387 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:44,395 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:44,410 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:44,522 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/b85c7c89-faa2-40b9-a9fa-a6c36788bead
2025-08-07 03:13:44,536 - DEBUG - =>VN ctest-trans_right_vn-13910358 has no policy to be verified
2025-08-07 03:13:44,536 - DEBUG - Verifying the vn in opserver
2025-08-07 03:13:44,536 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358 virtual network link through opserver 10.0.0.56
2025-08-07 03:13:44,536 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:13:44,552 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358?flat'}
2025-08-07 03:13:44,552 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358 virtual network link through opserver 10.0.0.130
2025-08-07 03:13:44,552 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:13:44,571 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358?flat'}
2025-08-07 03:13:44,571 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358 virtual network link through opserver 10.0.0.249
2025-08-07 03:13:44,571 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:13:44,587 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358?flat'}
2025-08-07 03:13:44,587 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87662300:ctest-trans_right_vn-13910358 is found in opserver
2025-08-07 03:13:44,613 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:13:44,628 - DEBUG - VRF ids for VN ctest-trans_right_vn-13910358: {}
2025-08-07 03:13:45,758 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:13:47,148 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1)
2025-08-07 03:13:47,149 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-48551302']
2025-08-07 03:13:47,466 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-48551302'] created succesfully
2025-08-07 03:13:47,848 - DEBUG - Image by name tiny_trans_fw not found
2025-08-07 03:13:47,848 - DEBUG - Installing image tiny_trans_fw
2025-08-07 03:13:47,848 - 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-07 03:13:47,848 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-07 03:13:47,848 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-07 03:13:47,886 - 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-07 03:13:47,932 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-07 03:13:47,932 - DEBUG - Unzip image
2025-08-07 03:13:47,933 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-07 03:13:47,959 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-07 03:13:47,960 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-48551302']
2025-08-07 03:13:48,085 - INFO - Service template: ['default-domain', 'ctest-service_template_1-48551302'] deleted successfully.
2025-08-07 03:13:48,085 - INFO - Skip interface_detach for VM ctest-right_vm-80502858 in state BUILD
2025-08-07 03:13:48,087 - INFO - Deleting VM ctest-right_vm-80502858
2025-08-07 03:13:48,087 - ERROR - VM ctest-right_vm-80502858 has failed to come up
2025-08-07 03:13:48,087 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:48,087 - ERROR - VM ctest-right_vm-80502858 has failed to come up
2025-08-07 03:13:48,087 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:48,211 - INFO - Skip interface_detach for VM ctest-left_vm-94652711 in state BUILD
2025-08-07 03:13:48,211 - INFO - Deleting VM ctest-left_vm-94652711
2025-08-07 03:13:48,212 - ERROR - VM ctest-left_vm-94652711 has failed to come up
2025-08-07 03:13:48,212 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:48,212 - ERROR - VM ctest-left_vm-94652711 has failed to come up
2025-08-07 03:13:48,212 - ERROR - Nova failed to get host of the VM
2025-08-07 03:13:48,297 - INFO - Deleting VN ctest-trans_right_vn-13910358
2025-08-07 03:13:48,482 - DEBUG - Response for deleting network ()
2025-08-07 03:13:48,482 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/de151e55-2c17-4d59-82e4-b8fbfbc437bd
2025-08-07 03:13:48,490 - DEBUG - Response Code: 404
2025-08-07 03:13:48,490 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:48,508 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:48,529 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:48,628 - INFO - Validated that VN ctest-trans_right_vn-13910358 is not found in API Server
2025-08-07 03:13:48,672 - DEBUG - VN ctest-trans_right_vn-13910358 is not present in Agent 10.0.0.41
2025-08-07 03:13:48,685 - DEBUG - VN ctest-trans_right_vn-13910358 is not present in Agent 10.0.0.61
2025-08-07 03:13:48,686 - INFO - Validated that VN ctest-trans_right_vn-13910358 is not in any agent
2025-08-07 03:13:48,761 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-13910358 info
2025-08-07 03:13:48,761 - INFO - Deleting VN ctest-trans_left_vn-47884456
2025-08-07 03:13:48,951 - DEBUG - Response for deleting network ()
2025-08-07 03:13:48,952 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/d79c6ec6-4f5c-4592-af4c-dc8437081faf
2025-08-07 03:13:48,961 - DEBUG - Response Code: 404
2025-08-07 03:13:48,962 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:48,969 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:49,004 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:49,124 - INFO - Validated that VN ctest-trans_left_vn-47884456 is not found in API Server
2025-08-07 03:13:49,153 - DEBUG - VN ctest-trans_left_vn-47884456 is not present in Agent 10.0.0.41
2025-08-07 03:13:49,181 - DEBUG - VN ctest-trans_left_vn-47884456 is not present in Agent 10.0.0.61
2025-08-07 03:13:49,181 - INFO - Validated that VN ctest-trans_left_vn-47884456 is not in any agent
2025-08-07 03:13:49,252 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-47884456 info
2025-08-07 03:13:49,253 - INFO - Deleting VN ctest-right_vn-76905340
2025-08-07 03:13:49,303 - DEBUG - VN 91da2ac0-f815-4888-afa2-b0e3e6a748af still in use: Unable to complete operation on network 91da2ac0-f815-4888-afa2-b0e3e6a748af. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-1dbcc111-08a4-4f90-babe-b6cc93f08307']
2025-08-07 03:13:49,303 - WARNING - Deleting VN ctest-right_vn-76905340 failed..Will retry
2025-08-07 03:13:51,586 - DEBUG - Response for deleting network ()
2025-08-07 03:13:51,586 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8e41288d-f5cc-4ed3-a81d-fe013015fecb
2025-08-07 03:13:51,598 - DEBUG - Response Code: 404
2025-08-07 03:13:51,598 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:51,610 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:51,680 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:51,797 - INFO - Validated that VN ctest-right_vn-76905340 is not found in API Server
2025-08-07 03:13:51,812 - DEBUG - VN ctest-right_vn-76905340 is not present in Agent 10.0.0.41
2025-08-07 03:13:51,824 - DEBUG - VN ctest-right_vn-76905340 is not present in Agent 10.0.0.61
2025-08-07 03:13:51,824 - INFO - Validated that VN ctest-right_vn-76905340 is not in any agent
2025-08-07 03:13:51,864 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-76905340 info
2025-08-07 03:13:51,865 - INFO - Deleting VN ctest-left_vn-21835192
2025-08-07 03:13:52,072 - DEBUG - Response for deleting network ()
2025-08-07 03:13:52,072 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e0b295fa-db7c-4671-9811-9d00ceaeb6c7
2025-08-07 03:13:52,080 - DEBUG - Response Code: 404
2025-08-07 03:13:52,080 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:52,088 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:52,117 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:52,219 - INFO - Validated that VN ctest-left_vn-21835192 is not found in API Server
2025-08-07 03:13:52,237 - DEBUG - VN ctest-left_vn-21835192 is not present in Agent 10.0.0.41
2025-08-07 03:13:52,250 - DEBUG - VN ctest-left_vn-21835192 is not present in Agent 10.0.0.61
2025-08-07 03:13:52,250 - INFO - Validated that VN ctest-left_vn-21835192 is not in any agent
2025-08-07 03:13:52,294 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-21835192 info
2025-08-07 03:13:52,294 - INFO - Deleting VN ctest-mgmt_vn-31561516
2025-08-07 03:13:52,502 - DEBUG - Response for deleting network ()
2025-08-07 03:13:52,502 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/898412b9-94d3-4555-a5a3-1a722d0e8246
2025-08-07 03:13:52,511 - DEBUG - Response Code: 404
2025-08-07 03:13:52,511 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:13:52,528 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:13:52,551 - DEBUG - Requesting: http://10.0.0.56:8082/project/11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:52,659 - INFO - Validated that VN ctest-mgmt_vn-31561516 is not found in API Server
2025-08-07 03:13:52,677 - DEBUG - VN ctest-mgmt_vn-31561516 is not present in Agent 10.0.0.41
2025-08-07 03:13:52,695 - DEBUG - VN ctest-mgmt_vn-31561516 is not present in Agent 10.0.0.61
2025-08-07 03:13:52,695 - INFO - Validated that VN ctest-mgmt_vn-31561516 is not in any agent
2025-08-07 03:13:52,738 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-31561516 info
2025-08-07 03:13:54,026 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 03:13:54,026 - INFO -
2025-08-07 03:13:54,026 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:18]
2025-08-07 03:13:54,026 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:13:54,803 - INFO - Deleted project: ctest-TestSvcRegr-87662300, ID : 11216b87-c86f-4e4c-9a42-ae6e6ab5794e
2025-08-07 03:13:55,009 - INFO - Domain Default found not creating
2025-08-07 03:13:55,171 - INFO - Project ctest-TestSvcRegr-46852798 not found, creating it
2025-08-07 03:13:55,777 - INFO - Created Project:ctest-TestSvcRegr-46852798, ID : f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:13:57,422 - INFO - ================================================================================
2025-08-07 03:13:57,422 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-07 03:13:58,738 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.61': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2025-08-07 03:13:58,738 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:13:58,738 - INFO -
2025-08-07 03:13:59,677 - DEBUG - Response for create_network : {'network': {'id': '8613b8d9-c0c8-485e-b425-5411b91a96e7', 'name': 'ctest-mgmt_vn-66959420', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'ctest-mgmt_vn-66959420'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:13:59,947 - DEBUG - Response for create_subnet : {'subnet': {'id': '1332ecca-6fe7-4b31-b334-7d56c8e24015', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': '8613b8d9-c0c8-485e-b425-5411b91a96e7', 'ip_version': 4, 'cidr': '129.122.109.0/26', 'allocation_pools': [{'start': '129.122.109.2', 'end': '129.122.109.62'}], 'gateway_ip': '129.122.109.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.122.109.2', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:13:59,980 - INFO - Created VN ctest-mgmt_vn-66959420
2025-08-07 03:13:59,993 - DEBUG - VN ctest-mgmt_vn-66959420 UUID is 8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,085 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:00,392 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:00,434 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:00,456 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:00,583 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,605 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,624 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:00,646 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:00,664 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/84b72b44-9ad5-4187-9dc5-e56d9330f490
2025-08-07 03:14:00,678 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:00,678 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,694 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:00,719 - INFO - Verified VN network id 12 for VN 8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,720 - INFO - Verifications in API Server for VN ctest-mgmt_vn-66959420 passed
2025-08-07 03:14:00,720 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,736 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:00,747 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:00,760 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/84b72b44-9ad5-4187-9dc5-e56d9330f490
2025-08-07 03:14:00,781 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9661268871021611102', 'uuid-lslong': '12980873936062027495'}, 'enable': 'true', 'created': '2025-08-07T03:13:59', 'last-modified': '2025-08-07T03:13:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.893890'}
2025-08-07 03:14:00,793 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:00,803 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9661268871021611102', 'uuid-lslong': '12980873936062027495'}, 'enable': 'true', 'created': '2025-08-07T03:13:59', 'last-modified': '2025-08-07T03:13:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.918620'}
2025-08-07 03:14:00,813 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:00,824 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9661268871021611102', 'uuid-lslong': '12980873936062027495'}, 'enable': 'true', 'created': '2025-08-07T03:13:59', 'last-modified': '2025-08-07T03:13:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.943600'}
2025-08-07 03:14:00,832 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:00,833 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-66959420 passed
2025-08-07 03:14:00,833 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-66959420 in API_Server ======
2025-08-07 03:14:00,833 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:00,842 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:00,858 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:00,974 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8613b8d9-c0c8-485e-b425-5411b91a96e7
2025-08-07 03:14:00,990 - DEBUG - =>VN ctest-mgmt_vn-66959420 has no policy to be verified
2025-08-07 03:14:00,990 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:00,990 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:00,990 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:01,304 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:01,380 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420?flat'}
2025-08-07 03:14:01,380 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:01,380 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:01,699 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:01,915 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420?flat'}
2025-08-07 03:14:01,916 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:01,916 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:02,224 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:02,293 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420?flat'}
2025-08-07 03:14:02,293 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-66959420 is found in opserver
2025-08-07 03:14:02,324 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:02,337 - DEBUG - VRF ids for VN ctest-mgmt_vn-66959420: {}
2025-08-07 03:14:02,660 - DEBUG - Response for create_network : {'network': {'id': '7a537c53-40c6-4763-9613-abb4ee61282c', 'name': 'service-left-vn-14563677', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'service-left-vn-14563677'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:14:02,900 - DEBUG - Response for create_subnet : {'subnet': {'id': '922aecd9-d62c-49e6-b8b2-f4dc15dfcfde', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': '7a537c53-40c6-4763-9613-abb4ee61282c', 'ip_version': 4, 'cidr': '17.77.190.128/26', 'allocation_pools': [{'start': '17.77.190.130', 'end': '17.77.190.190'}], 'gateway_ip': '17.77.190.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.77.190.130', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:14:02,928 - INFO - Created VN service-left-vn-14563677
2025-08-07 03:14:02,943 - DEBUG - VN service-left-vn-14563677 UUID is 7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,046 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:03,054 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:03,071 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:03,172 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,186 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,199 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:03,211 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:03,222 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6b534d56-52a3-497f-bf62-fa430ee87e79
2025-08-07 03:14:03,231 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:03,231 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,244 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:03,268 - INFO - Verified VN network id 13 for VN 7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,268 - INFO - Verifications in API Server for VN service-left-vn-14563677 passed
2025-08-07 03:14:03,268 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,282 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:03,293 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:03,303 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/6b534d56-52a3-497f-bf62-fa430ee87e79
2025-08-07 03:14:03,320 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8814525592705058659', 'uuid-lslong': '10814175923828500524'}, 'enable': 'true', 'created': '2025-08-07T03:14:02', 'last-modified': '2025-08-07T03:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.487758'}
2025-08-07 03:14:03,328 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:03,335 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8814525592705058659', 'uuid-lslong': '10814175923828500524'}, 'enable': 'true', 'created': '2025-08-07T03:14:02', 'last-modified': '2025-08-07T03:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.499922'}
2025-08-07 03:14:03,343 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:03,350 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8814525592705058659', 'uuid-lslong': '10814175923828500524'}, 'enable': 'true', 'created': '2025-08-07T03:14:02', 'last-modified': '2025-08-07T03:14:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520607'}
2025-08-07 03:14:03,357 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:03,357 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-14563677 passed
2025-08-07 03:14:03,357 - DEBUG - ====Verifying policy data for service-left-vn-14563677 in API_Server ======
2025-08-07 03:14:03,357 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:03,365 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:03,381 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:03,479 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a537c53-40c6-4763-9613-abb4ee61282c
2025-08-07 03:14:03,492 - DEBUG - =>VN service-left-vn-14563677 has no policy to be verified
2025-08-07 03:14:03,492 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:03,492 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:03,492 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:03,563 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677?flat'}
2025-08-07 03:14:03,563 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:03,563 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:03,630 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677?flat'}
2025-08-07 03:14:03,630 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:03,630 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:03,691 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677?flat'}
2025-08-07 03:14:03,691 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:service-left-vn-14563677 is found in opserver
2025-08-07 03:14:03,721 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:03,737 - DEBUG - VRF ids for VN service-left-vn-14563677: {}
2025-08-07 03:14:04,055 - DEBUG - Response for create_network : {'network': {'id': '8c8f5b99-c038-42e3-8190-f884f7b02354', 'name': 'service-right-vn-09443257', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'service-right-vn-09443257'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:14:04,337 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cab2cc0d-a244-412d-9c9c-3a08c6045823', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': '8c8f5b99-c038-42e3-8190-f884f7b02354', 'ip_version': 4, 'cidr': '126.50.108.0/26', 'allocation_pools': [{'start': '126.50.108.2', 'end': '126.50.108.62'}], 'gateway_ip': '126.50.108.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.50.108.2', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:14:04,366 - INFO - Created VN service-right-vn-09443257
2025-08-07 03:14:04,385 - DEBUG - VN service-right-vn-09443257 UUID is 8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,478 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:04,485 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:04,501 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:04,606 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,620 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,632 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:04,643 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:04,654 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/03e6643c-f296-473d-85f6-50a4679b40a4
2025-08-07 03:14:04,661 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:04,661 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,672 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:04,693 - INFO - Verified VN network id 14 for VN 8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,693 - INFO - Verifications in API Server for VN service-right-vn-09443257 passed
2025-08-07 03:14:04,693 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,705 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:04,713 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:04,722 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/03e6643c-f296-473d-85f6-50a4679b40a4
2025-08-07 03:14:04,739 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10128414802892571363', 'uuid-lslong': '9336235277513925460'}, 'enable': 'true', 'created': '2025-08-07T03:14:03', 'last-modified': '2025-08-07T03:14:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.482852'}
2025-08-07 03:14:04,746 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:04,753 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10128414802892571363', 'uuid-lslong': '9336235277513925460'}, 'enable': 'true', 'created': '2025-08-07T03:14:03', 'last-modified': '2025-08-07T03:14:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.495963'}
2025-08-07 03:14:04,762 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:04,770 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10128414802892571363', 'uuid-lslong': '9336235277513925460'}, 'enable': 'true', 'created': '2025-08-07T03:14:03', 'last-modified': '2025-08-07T03:14:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.507250'}
2025-08-07 03:14:04,779 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:04,779 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-09443257 passed
2025-08-07 03:14:04,779 - DEBUG - ====Verifying policy data for service-right-vn-09443257 in API_Server ======
2025-08-07 03:14:04,779 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:04,788 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:04,803 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:04,912 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/8c8f5b99-c038-42e3-8190-f884f7b02354
2025-08-07 03:14:04,929 - DEBUG - =>VN service-right-vn-09443257 has no policy to be verified
2025-08-07 03:14:04,929 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:04,929 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:04,929 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:04,946 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257?flat'}
2025-08-07 03:14:04,947 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:04,947 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:05,021 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257?flat'}
2025-08-07 03:14:05,021 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:05,021 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:05,091 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257?flat'}
2025-08-07 03:14:05,091 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:service-right-vn-09443257 is found in opserver
2025-08-07 03:14:05,118 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:05,134 - DEBUG - VRF ids for VN service-right-vn-09443257: {}
2025-08-07 03:14:05,194 - DEBUG - Services list from nova: [, , , ]
2025-08-07 03:14:06,266 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:14:07,732 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1)
2025-08-07 03:14:07,733 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-83183438']
2025-08-07 03:14:08,047 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-83183438'] created succesfully
2025-08-07 03:14:08,464 - DEBUG - Image by name tiny_nat_fw not found
2025-08-07 03:14:08,464 - DEBUG - Installing image tiny_nat_fw
2025-08-07 03:14:08,464 - 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-07 03:14:08,465 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:08,465 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:08,465 - DEBUG - Unzip image
2025-08-07 03:14:08,465 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:08,485 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-07 03:14:08,488 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-83183438']
2025-08-07 03:14:08,638 - INFO - Service template: ['default-domain', 'ctest-service_template_1-83183438'] deleted successfully.
2025-08-07 03:14:08,638 - INFO - Skip interface_detach for VM ctest-right_vm-31468010 in state BUILD
2025-08-07 03:14:08,638 - INFO - Deleting VM ctest-right_vm-31468010
2025-08-07 03:14:08,677 - ERROR - VM ctest-right_vm-31468010 has failed to come up
2025-08-07 03:14:08,677 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:08,677 - ERROR - VM ctest-right_vm-31468010 has failed to come up
2025-08-07 03:14:08,677 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:08,822 - INFO - Skip interface_detach for VM ctest-left_vm-96742432 in state BUILD
2025-08-07 03:14:08,822 - INFO - Deleting VM ctest-left_vm-96742432
2025-08-07 03:14:08,822 - ERROR - VM ctest-left_vm-96742432 has failed to come up
2025-08-07 03:14:08,822 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:08,822 - ERROR - VM ctest-left_vm-96742432 has failed to come up
2025-08-07 03:14:08,822 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:08,910 - INFO - Deleting VN service-right-vn-09443257
2025-08-07 03:14:08,966 - DEBUG - VN 8c8f5b99-c038-42e3-8190-f884f7b02354 still in use: Unable to complete operation on network 8c8f5b99-c038-42e3-8190-f884f7b02354. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-16d95191-9d71-4d1a-962a-9e8fa48de9cf']
2025-08-07 03:14:08,966 - WARNING - Deleting VN service-right-vn-09443257 failed..Will retry
2025-08-07 03:14:11,159 - DEBUG - Response for deleting network ()
2025-08-07 03:14:11,159 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/be81feba-780d-4e7f-86a4-ea1e03ce947a
2025-08-07 03:14:11,169 - DEBUG - Response Code: 404
2025-08-07 03:14:11,169 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:11,192 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:11,214 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:11,335 - INFO - Validated that VN service-right-vn-09443257 is not found in API Server
2025-08-07 03:14:11,355 - DEBUG - VN service-right-vn-09443257 is not present in Agent 10.0.0.41
2025-08-07 03:14:11,369 - DEBUG - VN service-right-vn-09443257 is not present in Agent 10.0.0.61
2025-08-07 03:14:11,369 - INFO - Validated that VN service-right-vn-09443257 is not in any agent
2025-08-07 03:14:11,427 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-09443257 info
2025-08-07 03:14:11,427 - INFO - Deleting VN service-left-vn-14563677
2025-08-07 03:14:11,653 - DEBUG - Response for deleting network ()
2025-08-07 03:14:11,654 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a958ff24-5cac-4c06-a222-c7e934829d5f
2025-08-07 03:14:11,668 - DEBUG - Response Code: 404
2025-08-07 03:14:11,668 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:11,693 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:11,716 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:11,822 - INFO - Validated that VN service-left-vn-14563677 is not found in API Server
2025-08-07 03:14:11,842 - DEBUG - VN service-left-vn-14563677 is not present in Agent 10.0.0.41
2025-08-07 03:14:11,857 - DEBUG - VN service-left-vn-14563677 is not present in Agent 10.0.0.61
2025-08-07 03:14:11,857 - INFO - Validated that VN service-left-vn-14563677 is not in any agent
2025-08-07 03:14:11,923 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-14563677 info
2025-08-07 03:14:11,923 - INFO - Deleting VN ctest-mgmt_vn-66959420
2025-08-07 03:14:12,134 - DEBUG - Response for deleting network ()
2025-08-07 03:14:12,134 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/adb9f6b0-28d3-4351-9a5a-4cb5dd1e5f61
2025-08-07 03:14:12,143 - DEBUG - Response Code: 404
2025-08-07 03:14:12,143 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:12,166 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:12,211 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:12,319 - INFO - Validated that VN ctest-mgmt_vn-66959420 is not found in API Server
2025-08-07 03:14:12,334 - DEBUG - VN ctest-mgmt_vn-66959420 is not present in Agent 10.0.0.41
2025-08-07 03:14:12,348 - DEBUG - VN ctest-mgmt_vn-66959420 is not present in Agent 10.0.0.61
2025-08-07 03:14:12,348 - INFO - Validated that VN ctest-mgmt_vn-66959420 is not in any agent
2025-08-07 03:14:12,388 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-66959420 info
2025-08-07 03:14:13,682 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 03:14:13,682 - INFO -
2025-08-07 03:14:13,682 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:16]
2025-08-07 03:14:13,683 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:14:13,687 - INFO - ================================================================================
2025-08-07 03:14:13,687 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-07 03:14:14,981 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.61': {'10.20.0.17': '0', '10.20.0.25': '0'}} with
2025-08-07 03:14:14,981 - INFO - Initial checks done. Running the testcase now
2025-08-07 03:14:14,981 - INFO -
2025-08-07 03:14:15,284 - DEBUG - Response for create_network : {'network': {'id': '9b8f59a2-da4f-406c-96f1-f4b2e65d7c45', 'name': 'ctest-mgmt_vn-85476667', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'ctest-mgmt_vn-85476667'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:14:15,536 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aded33f2-56c0-4d8f-a07c-ceda9a0a3af8', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': '9b8f59a2-da4f-406c-96f1-f4b2e65d7c45', 'ip_version': 4, 'cidr': '185.230.45.64/26', 'allocation_pools': [{'start': '185.230.45.66', 'end': '185.230.45.126'}], 'gateway_ip': '185.230.45.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.230.45.66', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:14:15,567 - INFO - Created VN ctest-mgmt_vn-85476667
2025-08-07 03:14:15,586 - DEBUG - VN ctest-mgmt_vn-85476667 UUID is 9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,693 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:15,700 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:15,728 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:15,848 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,868 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,890 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:15,907 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:15,917 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b71870c7-70e3-47bf-a84c-cab30738edcb
2025-08-07 03:14:15,926 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:15,927 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,942 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:15,964 - INFO - Verified VN network id 12 for VN 9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,964 - INFO - Verifications in API Server for VN ctest-mgmt_vn-85476667 passed
2025-08-07 03:14:15,964 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:15,975 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:15,987 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:15,995 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b71870c7-70e3-47bf-a84c-cab30738edcb
2025-08-07 03:14:16,009 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11209276553530654828', 'uuid-lslong': '10876743624282700869'}, 'enable': 'true', 'created': '2025-08-07T03:14:15', 'last-modified': '2025-08-07T03:14:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.542945'}
2025-08-07 03:14:16,017 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:16,025 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11209276553530654828', 'uuid-lslong': '10876743624282700869'}, 'enable': 'true', 'created': '2025-08-07T03:14:15', 'last-modified': '2025-08-07T03:14:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.556426'}
2025-08-07 03:14:16,034 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:16,041 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11209276553530654828', 'uuid-lslong': '10876743624282700869'}, 'enable': 'true', 'created': '2025-08-07T03:14:15', 'last-modified': '2025-08-07T03:14:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.573021'}
2025-08-07 03:14:16,049 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-07 03:14:16,049 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-85476667 passed
2025-08-07 03:14:16,049 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-85476667 in API_Server ======
2025-08-07 03:14:16,049 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:16,058 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:16,074 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:16,192 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9b8f59a2-da4f-406c-96f1-f4b2e65d7c45
2025-08-07 03:14:16,210 - DEBUG - =>VN ctest-mgmt_vn-85476667 has no policy to be verified
2025-08-07 03:14:16,210 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:16,210 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:16,210 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:16,285 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667?flat'}
2025-08-07 03:14:16,285 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:16,285 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:16,303 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667?flat'}
2025-08-07 03:14:16,304 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:16,304 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:16,320 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667?flat'}
2025-08-07 03:14:16,320 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:ctest-mgmt_vn-85476667 is found in opserver
2025-08-07 03:14:16,351 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:16,366 - DEBUG - VRF ids for VN ctest-mgmt_vn-85476667: {}
2025-08-07 03:14:16,711 - DEBUG - Response for create_network : {'network': {'id': '423a0bdb-5e09-4aa9-b5b5-47d42b2176f3', 'name': 'ctest-left_vn-08054755', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'ctest-left_vn-08054755'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:14:16,964 - DEBUG - Response for create_subnet : {'subnet': {'id': '1d342512-e5f2-4cb5-b64e-93566f6a5fae', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': '423a0bdb-5e09-4aa9-b5b5-47d42b2176f3', 'ip_version': 4, 'cidr': '70.168.111.0/26', 'allocation_pools': [{'start': '70.168.111.2', 'end': '70.168.111.62'}], 'gateway_ip': '70.168.111.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.168.111.2', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:14:16,991 - INFO - Created VN ctest-left_vn-08054755
2025-08-07 03:14:17,005 - DEBUG - VN ctest-left_vn-08054755 UUID is 423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,111 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:17,120 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:17,136 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:17,244 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,260 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,273 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:17,287 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:17,298 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/58e09ec2-c7fd-486b-89c0-cf1bb5bf9043
2025-08-07 03:14:17,305 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:17,305 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,319 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:17,343 - INFO - Verified VN network id 13 for VN 423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,343 - INFO - Verifications in API Server for VN ctest-left_vn-08054755 passed
2025-08-07 03:14:17,343 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,357 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:17,368 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:17,378 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/58e09ec2-c7fd-486b-89c0-cf1bb5bf9043
2025-08-07 03:14:17,393 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4772139791955872425', 'uuid-lslong': '13093450468231837427'}, 'enable': 'true', 'created': '2025-08-07T03:14:16', 'last-modified': '2025-08-07T03:14:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.492152'}
2025-08-07 03:14:17,401 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:17,410 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4772139791955872425', 'uuid-lslong': '13093450468231837427'}, 'enable': 'true', 'created': '2025-08-07T03:14:16', 'last-modified': '2025-08-07T03:14:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.501840'}
2025-08-07 03:14:17,418 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:17,426 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4772139791955872425', 'uuid-lslong': '13093450468231837427'}, 'enable': 'true', 'created': '2025-08-07T03:14:16', 'last-modified': '2025-08-07T03:14:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.517829'}
2025-08-07 03:14:17,434 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-07 03:14:17,434 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-08054755 passed
2025-08-07 03:14:17,434 - DEBUG - ====Verifying policy data for ctest-left_vn-08054755 in API_Server ======
2025-08-07 03:14:17,434 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:17,443 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:17,459 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:17,569 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/423a0bdb-5e09-4aa9-b5b5-47d42b2176f3
2025-08-07 03:14:17,585 - DEBUG - =>VN ctest-left_vn-08054755 has no policy to be verified
2025-08-07 03:14:17,585 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:17,585 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:17,585 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:17,601 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755?flat'}
2025-08-07 03:14:17,601 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:17,601 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:17,619 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755?flat'}
2025-08-07 03:14:17,619 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:17,619 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:17,638 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755?flat'}
2025-08-07 03:14:17,638 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:ctest-left_vn-08054755 is found in opserver
2025-08-07 03:14:17,668 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:17,687 - DEBUG - VRF ids for VN ctest-left_vn-08054755: {}
2025-08-07 03:14:18,049 - DEBUG - Response for create_network : {'network': {'id': 'dd0dae9d-062f-4529-a51a-bf9895cc01d8', 'name': 'ctest-right_vn-30218549', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'project_id': 'f42200fdaf994ff0a7f2165f625311de', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46852798', 'ctest-right_vn-30218549'], 'port_security_enabled': True, 'description': ''}}
2025-08-07 03:14:18,314 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c297eb1d-4793-4d60-a0f0-31d910901f0e', 'name': '', 'tenant_id': 'f42200fdaf994ff0a7f2165f625311de', 'network_id': 'dd0dae9d-062f-4529-a51a-bf9895cc01d8', 'ip_version': 4, 'cidr': '93.178.252.64/26', 'allocation_pools': [{'start': '93.178.252.66', 'end': '93.178.252.126'}], 'gateway_ip': '93.178.252.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '93.178.252.66', 'tags': [], 'project_id': 'f42200fdaf994ff0a7f2165f625311de'}}
2025-08-07 03:14:18,347 - INFO - Created VN ctest-right_vn-30218549
2025-08-07 03:14:18,364 - DEBUG - VN ctest-right_vn-30218549 UUID is dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,469 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:18,477 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:18,494 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:18,608 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,635 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,648 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:18,658 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:18,668 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/cd1cbe1f-178f-4829-a99c-b0d580d2f132
2025-08-07 03:14:18,679 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:18,679 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,693 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:18,716 - INFO - Verified VN network id 14 for VN dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,716 - INFO - Verifications in API Server for VN ctest-right_vn-30218549 passed
2025-08-07 03:14:18,716 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,730 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:18,739 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:18,750 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/cd1cbe1f-178f-4829-a99c-b0d580d2f132
2025-08-07 03:14:18,772 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15928579446516172073', 'uuid-lslong': '11897032027721695704'}, 'enable': 'true', 'created': '2025-08-07T03:14:17', 'last-modified': '2025-08-07T03:14:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.534126'}
2025-08-07 03:14:18,782 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:18,789 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15928579446516172073', 'uuid-lslong': '11897032027721695704'}, 'enable': 'true', 'created': '2025-08-07T03:14:17', 'last-modified': '2025-08-07T03:14:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.556147'}
2025-08-07 03:14:18,798 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:18,805 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46852798', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15928579446516172073', 'uuid-lslong': '11897032027721695704'}, 'enable': 'true', 'created': '2025-08-07T03:14:17', 'last-modified': '2025-08-07T03:14:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.571127'}
2025-08-07 03:14:18,814 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-07 03:14:18,814 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-30218549 passed
2025-08-07 03:14:18,814 - DEBUG - ====Verifying policy data for ctest-right_vn-30218549 in API_Server ======
2025-08-07 03:14:18,814 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:18,822 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:18,835 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:18,934 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/dd0dae9d-062f-4529-a51a-bf9895cc01d8
2025-08-07 03:14:18,950 - DEBUG - =>VN ctest-right_vn-30218549 has no policy to be verified
2025-08-07 03:14:18,950 - DEBUG - Verifying the vn in opserver
2025-08-07 03:14:18,951 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549 virtual network link through opserver 10.0.0.56
2025-08-07 03:14:18,951 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-08-07 03:14:18,973 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549?flat'}
2025-08-07 03:14:18,973 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549 virtual network link through opserver 10.0.0.130
2025-08-07 03:14:18,973 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2025-08-07 03:14:18,989 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549?flat'}
2025-08-07 03:14:18,989 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549 virtual network link through opserver 10.0.0.249
2025-08-07 03:14:18,989 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-08-07 03:14:19,004 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549?flat'}
2025-08-07 03:14:19,004 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46852798:ctest-right_vn-30218549 is found in opserver
2025-08-07 03:14:19,031 - DEBUG - Do not have enough data to verify VN in agent
2025-08-07 03:14:19,043 - DEBUG - VRF ids for VN ctest-right_vn-30218549: {}
2025-08-07 03:14:20,108 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1)
2025-08-07 03:14:21,543 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2)
2025-08-07 03:14:21,543 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-45686550']
2025-08-07 03:14:21,872 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-45686550'] created succesfully
2025-08-07 03:14:22,229 - DEBUG - Image by name tiny_nat_fw not found
2025-08-07 03:14:22,229 - DEBUG - Installing image tiny_nat_fw
2025-08-07 03:14:22,229 - 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-07 03:14:22,230 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:22,230 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:22,230 - DEBUG - Unzip image
2025-08-07 03:14:22,230 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-07 03:14:22,265 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-07 03:14:22,278 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-45686550']
2025-08-07 03:14:22,426 - INFO - Service template: ['default-domain', 'ctest-service_template_1-45686550'] deleted successfully.
2025-08-07 03:14:22,427 - INFO - Skip interface_detach for VM ctest-right_vm-19192618 in state BUILD
2025-08-07 03:14:22,427 - INFO - Deleting VM ctest-right_vm-19192618
2025-08-07 03:14:22,427 - ERROR - VM ctest-right_vm-19192618 has failed to come up
2025-08-07 03:14:22,427 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:22,427 - ERROR - VM ctest-right_vm-19192618 has failed to come up
2025-08-07 03:14:22,427 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:22,581 - INFO - Skip interface_detach for VM ctest-left_vm-68759445 in state BUILD
2025-08-07 03:14:22,581 - INFO - Deleting VM ctest-left_vm-68759445
2025-08-07 03:14:22,581 - ERROR - VM ctest-left_vm-68759445 has failed to come up
2025-08-07 03:14:22,581 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:22,581 - ERROR - VM ctest-left_vm-68759445 has failed to come up
2025-08-07 03:14:22,581 - ERROR - Nova failed to get host of the VM
2025-08-07 03:14:22,710 - INFO - Deleting VN ctest-right_vn-30218549
2025-08-07 03:14:22,770 - DEBUG - VN dd0dae9d-062f-4529-a51a-bf9895cc01d8 still in use: Unable to complete operation on network dd0dae9d-062f-4529-a51a-bf9895cc01d8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-71c1dd70-1168-4b8e-902b-affd42ba73dd']
2025-08-07 03:14:22,770 - WARNING - Deleting VN ctest-right_vn-30218549 failed..Will retry
2025-08-07 03:14:25,062 - DEBUG - Response for deleting network ()
2025-08-07 03:14:25,063 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6b5d1810-d6e2-4779-9bf2-27ecd6b34c14
2025-08-07 03:14:25,076 - DEBUG - Response Code: 404
2025-08-07 03:14:25,076 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:25,084 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:25,135 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:25,320 - INFO - Validated that VN ctest-right_vn-30218549 is not found in API Server
2025-08-07 03:14:25,350 - DEBUG - VN ctest-right_vn-30218549 is not present in Agent 10.0.0.41
2025-08-07 03:14:25,364 - DEBUG - VN ctest-right_vn-30218549 is not present in Agent 10.0.0.61
2025-08-07 03:14:25,364 - INFO - Validated that VN ctest-right_vn-30218549 is not in any agent
2025-08-07 03:14:25,422 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-30218549 info
2025-08-07 03:14:25,422 - INFO - Deleting VN ctest-left_vn-08054755
2025-08-07 03:14:25,683 - DEBUG - Response for deleting network ()
2025-08-07 03:14:25,683 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/1cb306e0-49a0-4835-8710-4b910185e860
2025-08-07 03:14:25,697 - DEBUG - Response Code: 404
2025-08-07 03:14:25,697 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:25,707 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:25,765 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:25,878 - INFO - Validated that VN ctest-left_vn-08054755 is not found in API Server
2025-08-07 03:14:25,892 - DEBUG - VN ctest-left_vn-08054755 is not present in Agent 10.0.0.41
2025-08-07 03:14:25,905 - DEBUG - VN ctest-left_vn-08054755 is not present in Agent 10.0.0.61
2025-08-07 03:14:25,905 - INFO - Validated that VN ctest-left_vn-08054755 is not in any agent
2025-08-07 03:14:25,956 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-08054755 info
2025-08-07 03:14:25,957 - INFO - Deleting VN ctest-mgmt_vn-85476667
2025-08-07 03:14:26,164 - DEBUG - Response for deleting network ()
2025-08-07 03:14:26,164 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/12e56745-5894-4467-aecf-02edb40b4210
2025-08-07 03:14:26,171 - DEBUG - Response Code: 404
2025-08-07 03:14:26,171 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-08-07 03:14:26,178 - DEBUG - Requesting: http://10.0.0.56:8082/domain/fb2d80da-9971-40dc-80ca-c5bb54e98569
2025-08-07 03:14:26,196 - DEBUG - Requesting: http://10.0.0.56:8082/project/f42200fd-af99-4ff0-a7f2-165f625311de
2025-08-07 03:14:26,292 - INFO - Validated that VN ctest-mgmt_vn-85476667 is not found in API Server
2025-08-07 03:14:26,307 - DEBUG - VN ctest-mgmt_vn-85476667 is not present in Agent 10.0.0.41
2025-08-07 03:14:26,320 - DEBUG - VN ctest-mgmt_vn-85476667 is not present in Agent 10.0.0.61
2025-08-07 03:14:26,320 - INFO - Validated that VN ctest-mgmt_vn-85476667 is not in any agent
2025-08-07 03:14:26,367 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-85476667 info
2025-08-07 03:14:27,681 - DEBUG - No XMPP flaps were noticed during the test
2025-08-07 03:14:27,681 - INFO -
2025-08-07 03:14:27,681 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:14]
2025-08-07 03:14:27,682 - INFO - --------------------------------------------------------------------------------
2025-08-07 03:14:28,491 - INFO - Deleted project: ctest-TestSvcRegr-46852798, ID : f42200fd-af99-4ff0-a7f2-165f625311de