]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-1)
2025-10-06 02:43:50,977 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-85615440']
2025-10-06 02:43:51,277 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-85615440'] created succesfully
2025-10-06 02:43:51,670 - DEBUG - Image by name tiny_nat_fw not found
2025-10-06 02:43:51,670 - DEBUG - Installing image tiny_nat_fw
2025-10-06 02:43:51,670 - 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-10-06 02:43:51,671 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-06 02:43:51,671 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-06 02:43:51,689 - 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-10-06 02:43:51,784 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:43:51,784 - DEBUG - Unzip image
2025-10-06 02:43:51,784 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:43:51,804 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-06 02:43:51,805 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-85615440']
2025-10-06 02:43:51,934 - INFO - Service template: ['default-domain', 'ctest-service_template_1-85615440'] deleted successfully.
2025-10-06 02:43:51,934 - INFO - Skip interface_detach for VM ctest-right_vm-96160475 in state BUILD
2025-10-06 02:43:51,934 - INFO - Deleting VM ctest-right_vm-96160475
2025-10-06 02:43:51,973 - ERROR - VM ctest-right_vm-96160475 has failed to come up
2025-10-06 02:43:51,973 - ERROR - Nova failed to get host of the VM
2025-10-06 02:43:51,973 - ERROR - VM ctest-right_vm-96160475 has failed to come up
2025-10-06 02:43:51,973 - ERROR - Nova failed to get host of the VM
2025-10-06 02:43:52,098 - INFO - Skip interface_detach for VM ctest-left_vm-38350861 in state BUILD
2025-10-06 02:43:52,098 - INFO - Deleting VM ctest-left_vm-38350861
2025-10-06 02:43:52,098 - ERROR - VM ctest-left_vm-38350861 has failed to come up
2025-10-06 02:43:52,098 - ERROR - Nova failed to get host of the VM
2025-10-06 02:43:52,098 - ERROR - VM ctest-left_vm-38350861 has failed to come up
2025-10-06 02:43:52,098 - ERROR - Nova failed to get host of the VM
2025-10-06 02:43:52,181 - INFO - Deleting VN ctest-right_vn-32025569
2025-10-06 02:43:52,224 - DEBUG - VN 91edbdd4-ff9b-4074-aa9c-fcf508d4cf4a still in use: Unable to complete operation on network 91edbdd4-ff9b-4074-aa9c-fcf508d4cf4a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-04f654f6-ec44-4057-ad4e-f900173d294e']
2025-10-06 02:43:52,224 - WARNING - Deleting VN ctest-right_vn-32025569 failed..Will retry
2025-10-06 02:43:54,378 - DEBUG - Response for deleting network ()
2025-10-06 02:43:54,378 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/ad3e9014-3f45-4c20-b5d5-9b484ad7a1ff
2025-10-06 02:43:54,383 - DEBUG - Response Code: 404
2025-10-06 02:43:54,390 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:43:54,398 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:43:54,416 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:43:54,506 - INFO - Validated that VN ctest-right_vn-32025569 is not found in API Server
2025-10-06 02:43:54,524 - DEBUG - VN ctest-right_vn-32025569 is not present in Agent 10.0.0.49
2025-10-06 02:43:54,533 - DEBUG - VN ctest-right_vn-32025569 is not present in Agent 10.0.0.19
2025-10-06 02:43:54,534 - INFO - Validated that VN ctest-right_vn-32025569 is not in any agent
2025-10-06 02:43:54,568 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-32025569 info
2025-10-06 02:43:54,568 - INFO - Deleting VN ctest-left_vn-14791476
2025-10-06 02:43:54,717 - DEBUG - Response for deleting network ()
2025-10-06 02:43:54,717 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5f2db256-5afb-4f7a-bad8-0a5320b34ca0
2025-10-06 02:43:54,722 - DEBUG - Response Code: 404
2025-10-06 02:43:54,722 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:43:54,728 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:43:54,748 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:43:54,836 - INFO - Validated that VN ctest-left_vn-14791476 is not found in API Server
2025-10-06 02:43:54,849 - DEBUG - VN ctest-left_vn-14791476 is not present in Agent 10.0.0.49
2025-10-06 02:43:54,862 - DEBUG - VN ctest-left_vn-14791476 is not present in Agent 10.0.0.19
2025-10-06 02:43:54,862 - INFO - Validated that VN ctest-left_vn-14791476 is not in any agent
2025-10-06 02:43:54,895 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-14791476 info
2025-10-06 02:43:54,896 - INFO - Deleting VN ctest-mgmt_vn-26009469
2025-10-06 02:43:55,028 - DEBUG - Response for deleting network ()
2025-10-06 02:43:55,029 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2ba9ec9-b47a-4f47-838b-5d6e68221c47
2025-10-06 02:43:55,034 - DEBUG - Response Code: 404
2025-10-06 02:43:55,034 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:43:55,039 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:43:55,055 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:43:55,147 - INFO - Validated that VN ctest-mgmt_vn-26009469 is not found in API Server
2025-10-06 02:43:55,159 - DEBUG - VN ctest-mgmt_vn-26009469 is not present in Agent 10.0.0.49
2025-10-06 02:43:55,170 - DEBUG - VN ctest-mgmt_vn-26009469 is not present in Agent 10.0.0.19
2025-10-06 02:43:55,170 - INFO - Validated that VN ctest-mgmt_vn-26009469 is not in any agent
2025-10-06 02:43:55,199 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-26009469 info
2025-10-06 02:43:55,199 - INFO - Deleting VM ctest-vm-in-third-vn-12910660
2025-10-06 02:43:55,268 - INFO - Deleting VN ctest-third-vn-48432149
2025-10-06 02:43:55,309 - DEBUG - VN b9b65fb4-823f-40f3-b5ce-ae936e6053e2 still in use: Unable to complete operation on network b9b65fb4-823f-40f3-b5ce-ae936e6053e2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-17c8c7f1-df94-4998-940f-43cbb95458a1']
2025-10-06 02:43:55,309 - WARNING - Deleting VN ctest-third-vn-48432149 failed..Will retry
2025-10-06 02:43:57,444 - DEBUG - Response for deleting network ()
2025-10-06 02:43:58,708 - DEBUG - No XMPP flaps were noticed during the test
2025-10-06 02:43:58,709 - INFO -
2025-10-06 02:43:58,709 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:23]
2025-10-06 02:43:58,709 - INFO - --------------------------------------------------------------------------------
2025-10-06 02:43:58,711 - INFO - ================================================================================
2025-10-06 02:43:58,711 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-06 02:43:59,970 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-10-06 02:43:59,970 - INFO - Initial checks done. Running the testcase now
2025-10-06 02:43:59,970 - INFO -
2025-10-06 02:44:00,237 - DEBUG - Response for create_network : {'network': {'id': '3bb22bd6-6c12-4b9f-b959-688cffff56cf', 'name': 'ctest-mgmt_vn-50568069', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'project_id': 'c3720e8032d845a38a6bb27936ac63bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10391115', 'ctest-mgmt_vn-50568069'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:00,475 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fd25d9bc-2053-4b15-bf0e-1d24a46d58cd', 'name': '', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'network_id': '3bb22bd6-6c12-4b9f-b959-688cffff56cf', 'ip_version': 4, 'cidr': '47.201.241.192/26', 'allocation_pools': [{'start': '47.201.241.194', 'end': '47.201.241.254'}], 'gateway_ip': '47.201.241.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.201.241.194', 'tags': [], 'project_id': 'c3720e8032d845a38a6bb27936ac63bd'}}
2025-10-06 02:44:00,504 - INFO - Created VN ctest-mgmt_vn-50568069
2025-10-06 02:44:00,515 - DEBUG - VN ctest-mgmt_vn-50568069 UUID is 3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,586 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:00,595 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:00,606 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:00,701 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,712 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,721 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:00,731 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:00,737 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0b59d080-31a5-4baa-9206-826cc6263fd6
2025-10-06 02:44:00,744 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:00,744 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,752 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:00,767 - INFO - Verified VN network id 10 for VN 3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,767 - INFO - Verifications in API Server for VN ctest-mgmt_vn-50568069 passed
2025-10-06 02:44:00,767 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,777 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:00,784 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:00,793 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0b59d080-31a5-4baa-9206-826cc6263fd6
2025-10-06 02:44:00,809 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4301548794028379039', 'uuid-lslong': '13355821124743550671'}, 'enable': 'true', 'created': '2025-10-06T02:44:00', 'last-modified': '2025-10-06T02:44:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403771'}
2025-10-06 02:44:00,818 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:00,826 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4301548794028379039', 'uuid-lslong': '13355821124743550671'}, 'enable': 'true', 'created': '2025-10-06T02:44:00', 'last-modified': '2025-10-06T02:44:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428766'}
2025-10-06 02:44:00,830 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:00,837 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4301548794028379039', 'uuid-lslong': '13355821124743550671'}, 'enable': 'true', 'created': '2025-10-06T02:44:00', 'last-modified': '2025-10-06T02:44:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433119'}
2025-10-06 02:44:00,843 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:00,843 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-50568069 passed
2025-10-06 02:44:00,843 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-50568069 in API_Server ======
2025-10-06 02:44:00,843 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:00,848 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:00,857 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:00,961 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/3bb22bd6-6c12-4b9f-b959-688cffff56cf
2025-10-06 02:44:00,971 - DEBUG - =>VN ctest-mgmt_vn-50568069 has no policy to be verified
2025-10-06 02:44:00,971 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:00,971 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:00,971 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:00,982 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069?flat'}
2025-10-06 02:44:00,982 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:00,982 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:00,992 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069?flat'}
2025-10-06 02:44:00,992 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:00,992 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:01,004 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069?flat'}
2025-10-06 02:44:01,004 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10391115:ctest-mgmt_vn-50568069 is found in opserver
2025-10-06 02:44:01,025 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:01,034 - DEBUG - VRF ids for VN ctest-mgmt_vn-50568069: {}
2025-10-06 02:44:01,292 - DEBUG - Response for create_network : {'network': {'id': '967d6d1a-188a-4fab-b7d4-c279622275f7', 'name': 'ctest-left_vn-14258228', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'project_id': 'c3720e8032d845a38a6bb27936ac63bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10391115', 'ctest-left_vn-14258228'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:01,553 - DEBUG - Response for create_subnet : {'subnet': {'id': '7cd99c58-70c9-4cd5-aff8-0df14d7299a1', 'name': '', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'network_id': '967d6d1a-188a-4fab-b7d4-c279622275f7', 'ip_version': 4, 'cidr': '95.178.3.192/26', 'allocation_pools': [{'start': '95.178.3.194', 'end': '95.178.3.254'}], 'gateway_ip': '95.178.3.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.178.3.194', 'tags': [], 'project_id': 'c3720e8032d845a38a6bb27936ac63bd'}}
2025-10-06 02:44:01,574 - INFO - Created VN ctest-left_vn-14258228
2025-10-06 02:44:01,587 - DEBUG - VN ctest-left_vn-14258228 UUID is 967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,659 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:01,665 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:01,677 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:01,783 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,796 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,806 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:01,815 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:01,823 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/8ea5b1d9-f77d-4340-8c3d-4e5323ba6207
2025-10-06 02:44:01,834 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:01,834 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,843 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:01,858 - INFO - Verified VN network id 11 for VN 967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,858 - INFO - Verifications in API Server for VN ctest-left_vn-14258228 passed
2025-10-06 02:44:01,858 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:01,869 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:01,876 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:01,883 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/8ea5b1d9-f77d-4340-8c3d-4e5323ba6207
2025-10-06 02:44:01,898 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843943436626317227', 'uuid-lslong': '13246426230596728311'}, 'enable': 'true', 'created': '2025-10-06T02:44:01', 'last-modified': '2025-10-06T02:44:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402513'}
2025-10-06 02:44:01,906 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:01,910 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843943436626317227', 'uuid-lslong': '13246426230596728311'}, 'enable': 'true', 'created': '2025-10-06T02:44:01', 'last-modified': '2025-10-06T02:44:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419645'}
2025-10-06 02:44:01,915 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:01,920 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10843943436626317227', 'uuid-lslong': '13246426230596728311'}, 'enable': 'true', 'created': '2025-10-06T02:44:01', 'last-modified': '2025-10-06T02:44:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427656'}
2025-10-06 02:44:01,925 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:01,926 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-14258228 passed
2025-10-06 02:44:01,926 - DEBUG - ====Verifying policy data for ctest-left_vn-14258228 in API_Server ======
2025-10-06 02:44:01,926 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:01,931 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:01,942 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:02,035 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/967d6d1a-188a-4fab-b7d4-c279622275f7
2025-10-06 02:44:02,048 - DEBUG - =>VN ctest-left_vn-14258228 has no policy to be verified
2025-10-06 02:44:02,048 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:02,048 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:02,048 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:02,060 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228?flat'}
2025-10-06 02:44:02,060 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:02,060 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:02,072 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228?flat'}
2025-10-06 02:44:02,072 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:02,072 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:02,084 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228?flat'}
2025-10-06 02:44:02,084 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10391115:ctest-left_vn-14258228 is found in opserver
2025-10-06 02:44:02,103 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:02,111 - DEBUG - VRF ids for VN ctest-left_vn-14258228: {}
2025-10-06 02:44:02,411 - DEBUG - Response for create_network : {'network': {'id': '062268b5-467f-4590-b9cd-ede078179a9d', 'name': 'ctest-right_vn-20975936', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'project_id': 'c3720e8032d845a38a6bb27936ac63bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10391115', 'ctest-right_vn-20975936'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:02,669 - DEBUG - Response for create_subnet : {'subnet': {'id': '90214a76-b72a-41e6-8df7-52c847396934', 'name': '', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'network_id': '062268b5-467f-4590-b9cd-ede078179a9d', 'ip_version': 4, 'cidr': '11.203.187.128/26', 'allocation_pools': [{'start': '11.203.187.130', 'end': '11.203.187.190'}], 'gateway_ip': '11.203.187.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.203.187.130', 'tags': [], 'project_id': 'c3720e8032d845a38a6bb27936ac63bd'}}
2025-10-06 02:44:02,705 - INFO - Created VN ctest-right_vn-20975936
2025-10-06 02:44:02,717 - DEBUG - VN ctest-right_vn-20975936 UUID is 062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,795 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:02,802 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:02,818 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:02,914 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,926 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,935 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:02,942 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:02,950 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/905a0b9c-bcfa-4714-9512-97df1f17205a
2025-10-06 02:44:02,957 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:02,957 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,966 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:02,981 - INFO - Verified VN network id 12 for VN 062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,982 - INFO - Verifications in API Server for VN ctest-right_vn-20975936 passed
2025-10-06 02:44:02,982 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:02,992 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:03,000 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:03,006 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/905a0b9c-bcfa-4714-9512-97df1f17205a
2025-10-06 02:44:03,017 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '442030841216845200', 'uuid-lslong': '13388618815585622685'}, 'enable': 'true', 'created': '2025-10-06T02:44:02', 'last-modified': '2025-10-06T02:44:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411897'}
2025-10-06 02:44:03,023 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:03,028 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '442030841216845200', 'uuid-lslong': '13388618815585622685'}, 'enable': 'true', 'created': '2025-10-06T02:44:02', 'last-modified': '2025-10-06T02:44:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.431252'}
2025-10-06 02:44:03,033 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:03,038 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '442030841216845200', 'uuid-lslong': '13388618815585622685'}, 'enable': 'true', 'created': '2025-10-06T02:44:02', 'last-modified': '2025-10-06T02:44:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426093'}
2025-10-06 02:44:03,044 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:03,044 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-20975936 passed
2025-10-06 02:44:03,044 - DEBUG - ====Verifying policy data for ctest-right_vn-20975936 in API_Server ======
2025-10-06 02:44:03,044 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:03,050 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:03,061 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:03,170 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/062268b5-467f-4590-b9cd-ede078179a9d
2025-10-06 02:44:03,183 - DEBUG - =>VN ctest-right_vn-20975936 has no policy to be verified
2025-10-06 02:44:03,183 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:03,183 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:03,183 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:03,195 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936?flat'}
2025-10-06 02:44:03,195 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:03,195 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:03,207 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936?flat'}
2025-10-06 02:44:03,207 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:03,207 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:03,219 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936?flat'}
2025-10-06 02:44:03,219 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10391115:ctest-right_vn-20975936 is found in opserver
2025-10-06 02:44:03,238 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:03,247 - DEBUG - VRF ids for VN ctest-right_vn-20975936: {}
2025-10-06 02:44:03,473 - DEBUG - Response for create_network : {'network': {'id': '4e624018-4013-43b0-81ca-5fb7bcdceadf', 'name': 'ctest-trans_left_vn-76417983', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'project_id': 'c3720e8032d845a38a6bb27936ac63bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10391115', 'ctest-trans_left_vn-76417983'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:03,706 - DEBUG - Response for create_subnet : {'subnet': {'id': '66f2bfd2-54b9-4433-8d33-c8718bf67e22', 'name': '', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'network_id': '4e624018-4013-43b0-81ca-5fb7bcdceadf', 'ip_version': 4, 'cidr': '46.3.58.192/26', 'allocation_pools': [{'start': '46.3.58.194', 'end': '46.3.58.254'}], 'gateway_ip': '46.3.58.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '46.3.58.194', 'tags': [], 'project_id': 'c3720e8032d845a38a6bb27936ac63bd'}}
2025-10-06 02:44:03,727 - INFO - Created VN ctest-trans_left_vn-76417983
2025-10-06 02:44:03,739 - DEBUG - VN ctest-trans_left_vn-76417983 UUID is 4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:03,810 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:03,816 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:03,828 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:03,944 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:03,956 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:03,966 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:03,979 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:03,987 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/bfad0b92-da55-4e02-987e-b21e14f7ace5
2025-10-06 02:44:03,999 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-06 02:44:03,999 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:04,008 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:04,024 - INFO - Verified VN network id 13 for VN 4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:04,024 - INFO - Verifications in API Server for VN ctest-trans_left_vn-76417983 passed
2025-10-06 02:44:04,024 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:04,035 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:04,042 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:04,050 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/bfad0b92-da55-4e02-987e-b21e14f7ace5
2025-10-06 02:44:04,063 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5648147355574420400', 'uuid-lslong': '9352392818940504799'}, 'enable': 'true', 'created': '2025-10-06T02:44:03', 'last-modified': '2025-10-06T02:44:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402387'}
2025-10-06 02:44:04,069 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-06 02:44:04,074 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5648147355574420400', 'uuid-lslong': '9352392818940504799'}, 'enable': 'true', 'created': '2025-10-06T02:44:03', 'last-modified': '2025-10-06T02:44:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413429'}
2025-10-06 02:44:04,079 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-06 02:44:04,084 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5648147355574420400', 'uuid-lslong': '9352392818940504799'}, 'enable': 'true', 'created': '2025-10-06T02:44:03', 'last-modified': '2025-10-06T02:44:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.431519'}
2025-10-06 02:44:04,090 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-06 02:44:04,090 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-76417983 passed
2025-10-06 02:44:04,090 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-76417983 in API_Server ======
2025-10-06 02:44:04,090 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:04,096 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:04,107 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:04,196 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/4e624018-4013-43b0-81ca-5fb7bcdceadf
2025-10-06 02:44:04,209 - DEBUG - =>VN ctest-trans_left_vn-76417983 has no policy to be verified
2025-10-06 02:44:04,209 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:04,209 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:04,209 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:04,222 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983?flat'}
2025-10-06 02:44:04,223 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:04,223 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:04,234 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983?flat'}
2025-10-06 02:44:04,234 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:04,234 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:04,248 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983?flat'}
2025-10-06 02:44:04,248 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10391115:ctest-trans_left_vn-76417983 is found in opserver
2025-10-06 02:44:04,269 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:04,279 - DEBUG - VRF ids for VN ctest-trans_left_vn-76417983: {}
2025-10-06 02:44:04,523 - DEBUG - Response for create_network : {'network': {'id': '5bd8efe4-85c2-43e4-b73f-247c6be4141b', 'name': 'ctest-trans_right_vn-15015974', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'project_id': 'c3720e8032d845a38a6bb27936ac63bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10391115', 'ctest-trans_right_vn-15015974'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:04,778 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f8e33fa-03b7-4a90-a19c-971f288312fe', 'name': '', 'tenant_id': 'c3720e8032d845a38a6bb27936ac63bd', 'network_id': '5bd8efe4-85c2-43e4-b73f-247c6be4141b', 'ip_version': 4, 'cidr': '137.199.145.0/26', 'allocation_pools': [{'start': '137.199.145.2', 'end': '137.199.145.62'}], 'gateway_ip': '137.199.145.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '137.199.145.2', 'tags': [], 'project_id': 'c3720e8032d845a38a6bb27936ac63bd'}}
2025-10-06 02:44:04,799 - INFO - Created VN ctest-trans_right_vn-15015974
2025-10-06 02:44:04,813 - DEBUG - VN ctest-trans_right_vn-15015974 UUID is 5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:04,875 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:04,881 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:04,891 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:04,988 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,000 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,008 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:05,016 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:05,024 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/81798f0e-2f17-4d3c-9eee-f5e133e9191c
2025-10-06 02:44:05,031 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-06 02:44:05,031 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,041 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:05,059 - INFO - Verified VN network id 14 for VN 5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,059 - INFO - Verifications in API Server for VN ctest-trans_right_vn-15015974 passed
2025-10-06 02:44:05,059 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,069 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:05,078 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:05,086 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/81798f0e-2f17-4d3c-9eee-f5e133e9191c
2025-10-06 02:44:05,098 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6618303417196626916', 'uuid-lslong': '13204312749278237723'}, 'enable': 'true', 'created': '2025-10-06T02:44:04', 'last-modified': '2025-10-06T02:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.377429'}
2025-10-06 02:44:05,103 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-06 02:44:05,108 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6618303417196626916', 'uuid-lslong': '13204312749278237723'}, 'enable': 'true', 'created': '2025-10-06T02:44:04', 'last-modified': '2025-10-06T02:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.388407'}
2025-10-06 02:44:05,114 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-06 02:44:05,119 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10391115', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6618303417196626916', 'uuid-lslong': '13204312749278237723'}, 'enable': 'true', 'created': '2025-10-06T02:44:04', 'last-modified': '2025-10-06T02:44:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.396915'}
2025-10-06 02:44:05,125 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-06 02:44:05,125 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-15015974 passed
2025-10-06 02:44:05,125 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-15015974 in API_Server ======
2025-10-06 02:44:05,125 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:05,133 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:05,147 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:05,245 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/5bd8efe4-85c2-43e4-b73f-247c6be4141b
2025-10-06 02:44:05,253 - DEBUG - =>VN ctest-trans_right_vn-15015974 has no policy to be verified
2025-10-06 02:44:05,253 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:05,253 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:05,253 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:05,263 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974?flat'}
2025-10-06 02:44:05,263 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:05,263 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:05,275 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974?flat'}
2025-10-06 02:44:05,275 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:05,275 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:05,287 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974?flat'}
2025-10-06 02:44:05,287 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10391115:ctest-trans_right_vn-15015974 is found in opserver
2025-10-06 02:44:05,306 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:05,315 - DEBUG - VRF ids for VN ctest-trans_right_vn-15015974: {}
2025-10-06 02:44:06,320 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-1)
2025-10-06 02:44:07,608 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-2)
2025-10-06 02:44:07,608 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-75629715']
2025-10-06 02:44:07,917 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-75629715'] created succesfully
2025-10-06 02:44:08,294 - DEBUG - Image by name tiny_trans_fw not found
2025-10-06 02:44:08,294 - DEBUG - Installing image tiny_trans_fw
2025-10-06 02:44:08,294 - 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-10-06 02:44:08,295 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-06 02:44:08,295 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-06 02:44:08,313 - 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-10-06 02:44:08,346 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-06 02:44:08,346 - DEBUG - Unzip image
2025-10-06 02:44:08,346 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-06 02:44:08,366 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-06 02:44:08,367 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-75629715']
2025-10-06 02:44:08,477 - INFO - Service template: ['default-domain', 'ctest-service_template_1-75629715'] deleted successfully.
2025-10-06 02:44:08,477 - INFO - Skip interface_detach for VM ctest-right_vm-07088312 in state BUILD
2025-10-06 02:44:08,477 - INFO - Deleting VM ctest-right_vm-07088312
2025-10-06 02:44:08,478 - ERROR - VM ctest-right_vm-07088312 has failed to come up
2025-10-06 02:44:08,478 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:08,478 - ERROR - VM ctest-right_vm-07088312 has failed to come up
2025-10-06 02:44:08,478 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:08,560 - INFO - Skip interface_detach for VM ctest-left_vm-70367273 in state BUILD
2025-10-06 02:44:08,560 - INFO - Deleting VM ctest-left_vm-70367273
2025-10-06 02:44:08,560 - ERROR - VM ctest-left_vm-70367273 has failed to come up
2025-10-06 02:44:08,561 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:08,561 - ERROR - VM ctest-left_vm-70367273 has failed to come up
2025-10-06 02:44:08,561 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:08,628 - INFO - Deleting VN ctest-trans_right_vn-15015974
2025-10-06 02:44:08,794 - DEBUG - Response for deleting network ()
2025-10-06 02:44:08,794 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/cee49a84-1731-4f50-9b42-0e9d8f883ced
2025-10-06 02:44:08,802 - DEBUG - Response Code: 404
2025-10-06 02:44:08,802 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:08,811 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:08,833 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:08,914 - INFO - Validated that VN ctest-trans_right_vn-15015974 is not found in API Server
2025-10-06 02:44:08,930 - DEBUG - VN ctest-trans_right_vn-15015974 is not present in Agent 10.0.0.49
2025-10-06 02:44:08,954 - DEBUG - VN ctest-trans_right_vn-15015974 is not present in Agent 10.0.0.19
2025-10-06 02:44:08,954 - INFO - Validated that VN ctest-trans_right_vn-15015974 is not in any agent
2025-10-06 02:44:08,994 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-15015974 info
2025-10-06 02:44:08,994 - INFO - Deleting VN ctest-trans_left_vn-76417983
2025-10-06 02:44:09,139 - DEBUG - Response for deleting network ()
2025-10-06 02:44:09,139 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/fdb2cb57-cd29-467b-acca-8ad4ddb19e93
2025-10-06 02:44:09,144 - DEBUG - Response Code: 404
2025-10-06 02:44:09,145 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:09,150 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:09,175 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:09,272 - INFO - Validated that VN ctest-trans_left_vn-76417983 is not found in API Server
2025-10-06 02:44:09,282 - DEBUG - VN ctest-trans_left_vn-76417983 is not present in Agent 10.0.0.49
2025-10-06 02:44:09,301 - DEBUG - VN ctest-trans_left_vn-76417983 is not present in Agent 10.0.0.19
2025-10-06 02:44:09,301 - INFO - Validated that VN ctest-trans_left_vn-76417983 is not in any agent
2025-10-06 02:44:09,340 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-76417983 info
2025-10-06 02:44:09,340 - INFO - Deleting VN ctest-right_vn-20975936
2025-10-06 02:44:09,392 - DEBUG - VN 062268b5-467f-4590-b9cd-ede078179a9d still in use: Unable to complete operation on network 062268b5-467f-4590-b9cd-ede078179a9d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-eab747cc-de26-4332-8a7b-33fb61ec2303']
2025-10-06 02:44:09,392 - WARNING - Deleting VN ctest-right_vn-20975936 failed..Will retry
2025-10-06 02:44:11,532 - DEBUG - Response for deleting network ()
2025-10-06 02:44:11,533 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d15e4e5e-b87c-4c92-9653-dfebac26cf83
2025-10-06 02:44:11,538 - DEBUG - Response Code: 404
2025-10-06 02:44:11,538 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:11,546 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:11,599 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:11,682 - INFO - Validated that VN ctest-right_vn-20975936 is not found in API Server
2025-10-06 02:44:11,691 - DEBUG - VN ctest-right_vn-20975936 is not present in Agent 10.0.0.49
2025-10-06 02:44:11,699 - DEBUG - VN ctest-right_vn-20975936 is not present in Agent 10.0.0.19
2025-10-06 02:44:11,700 - INFO - Validated that VN ctest-right_vn-20975936 is not in any agent
2025-10-06 02:44:11,726 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-20975936 info
2025-10-06 02:44:11,726 - INFO - Deleting VN ctest-left_vn-14258228
2025-10-06 02:44:11,865 - DEBUG - Response for deleting network ()
2025-10-06 02:44:11,865 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b7868436-2e8a-4bd5-822d-8ba654b3509d
2025-10-06 02:44:11,870 - DEBUG - Response Code: 404
2025-10-06 02:44:11,870 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:11,875 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:11,897 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:11,989 - INFO - Validated that VN ctest-left_vn-14258228 is not found in API Server
2025-10-06 02:44:12,001 - DEBUG - VN ctest-left_vn-14258228 is not present in Agent 10.0.0.49
2025-10-06 02:44:12,013 - DEBUG - VN ctest-left_vn-14258228 is not present in Agent 10.0.0.19
2025-10-06 02:44:12,013 - INFO - Validated that VN ctest-left_vn-14258228 is not in any agent
2025-10-06 02:44:12,041 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-14258228 info
2025-10-06 02:44:12,041 - INFO - Deleting VN ctest-mgmt_vn-50568069
2025-10-06 02:44:12,218 - DEBUG - Response for deleting network ()
2025-10-06 02:44:12,219 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/c623d471-1561-4c59-9a8d-90ece2a2cee6
2025-10-06 02:44:12,224 - DEBUG - Response Code: 404
2025-10-06 02:44:12,224 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:12,230 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:12,240 - DEBUG - Requesting: http://10.0.0.33:8082/project/c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:12,353 - INFO - Validated that VN ctest-mgmt_vn-50568069 is not found in API Server
2025-10-06 02:44:12,364 - DEBUG - VN ctest-mgmt_vn-50568069 is not present in Agent 10.0.0.49
2025-10-06 02:44:12,374 - DEBUG - VN ctest-mgmt_vn-50568069 is not present in Agent 10.0.0.19
2025-10-06 02:44:12,374 - INFO - Validated that VN ctest-mgmt_vn-50568069 is not in any agent
2025-10-06 02:44:12,406 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-50568069 info
2025-10-06 02:44:13,674 - DEBUG - No XMPP flaps were noticed during the test
2025-10-06 02:44:13,674 - INFO -
2025-10-06 02:44:13,674 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-10-06 02:44:13,674 - INFO - --------------------------------------------------------------------------------
2025-10-06 02:44:14,439 - INFO - Deleted project: ctest-TestSvcRegr-10391115, ID : c3720e80-32d8-45a3-8a6b-b27936ac63bd
2025-10-06 02:44:14,609 - INFO - Domain Default found not creating
2025-10-06 02:44:14,762 - INFO - Project ctest-TestSvcRegr-58777482 not found, creating it
2025-10-06 02:44:15,333 - INFO - Created Project:ctest-TestSvcRegr-58777482, ID : 69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:17,271 - INFO - ================================================================================
2025-10-06 02:44:17,271 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-06 02:44:18,538 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-10-06 02:44:18,538 - INFO - Initial checks done. Running the testcase now
2025-10-06 02:44:18,539 - INFO -
2025-10-06 02:44:19,174 - DEBUG - Response for create_network : {'network': {'id': '46b2f387-f61a-4149-8435-12f7b3e84270', 'name': 'ctest-mgmt_vn-99603356', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'ctest-mgmt_vn-99603356'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:19,435 - DEBUG - Response for create_subnet : {'subnet': {'id': '53736384-c103-44e8-bcb6-ed616d6acd34', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': '46b2f387-f61a-4149-8435-12f7b3e84270', 'ip_version': 4, 'cidr': '214.164.224.128/26', 'allocation_pools': [{'start': '214.164.224.130', 'end': '214.164.224.190'}], 'gateway_ip': '214.164.224.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.164.224.130', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:19,456 - INFO - Created VN ctest-mgmt_vn-99603356
2025-10-06 02:44:19,466 - DEBUG - VN ctest-mgmt_vn-99603356 UUID is 46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:19,527 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:19,813 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:19,840 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:19,853 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:19,950 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:19,962 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:19,972 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:19,981 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:19,988 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/fa479d33-0128-4331-bfe0-4e9fc73a53a2
2025-10-06 02:44:19,996 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:19,996 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:20,006 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:20,026 - INFO - Verified VN network id 10 for VN 46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:20,026 - INFO - Verifications in API Server for VN ctest-mgmt_vn-99603356 passed
2025-10-06 02:44:20,027 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:20,037 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:20,044 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:20,051 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/fa479d33-0128-4331-bfe0-4e9fc73a53a2
2025-10-06 02:44:20,062 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5094401893784502601', 'uuid-lslong': '9526541441856717424'}, 'enable': 'true', 'created': '2025-10-06T02:44:19', 'last-modified': '2025-10-06T02:44:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.663593'}
2025-10-06 02:44:20,067 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:20,072 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5094401893784502601', 'uuid-lslong': '9526541441856717424'}, 'enable': 'true', 'created': '2025-10-06T02:44:19', 'last-modified': '2025-10-06T02:44:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.682189'}
2025-10-06 02:44:20,078 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:20,082 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5094401893784502601', 'uuid-lslong': '9526541441856717424'}, 'enable': 'true', 'created': '2025-10-06T02:44:19', 'last-modified': '2025-10-06T02:44:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.692789'}
2025-10-06 02:44:20,089 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:20,089 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-99603356 passed
2025-10-06 02:44:20,089 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-99603356 in API_Server ======
2025-10-06 02:44:20,089 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:20,095 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:20,108 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:20,198 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/46b2f387-f61a-4149-8435-12f7b3e84270
2025-10-06 02:44:20,207 - DEBUG - =>VN ctest-mgmt_vn-99603356 has no policy to be verified
2025-10-06 02:44:20,207 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:20,208 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:20,208 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:20,506 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:20,578 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356?flat'}
2025-10-06 02:44:20,578 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:20,578 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:20,868 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:20,915 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356?flat'}
2025-10-06 02:44:20,916 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:20,916 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:21,204 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:21,261 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356?flat'}
2025-10-06 02:44:21,261 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-99603356 is found in opserver
2025-10-06 02:44:21,280 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:21,290 - DEBUG - VRF ids for VN ctest-mgmt_vn-99603356: {}
2025-10-06 02:44:21,555 - DEBUG - Response for create_network : {'network': {'id': 'ade29f89-0fcc-4af4-b62f-a498ad2c150c', 'name': 'service-left-vn-19599250', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'service-left-vn-19599250'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:21,818 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cddc76f4-4a01-494f-8c91-6461900ceea2', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': 'ade29f89-0fcc-4af4-b62f-a498ad2c150c', 'ip_version': 4, 'cidr': '37.158.161.0/26', 'allocation_pools': [{'start': '37.158.161.2', 'end': '37.158.161.62'}], 'gateway_ip': '37.158.161.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.158.161.2', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:21,838 - INFO - Created VN service-left-vn-19599250
2025-10-06 02:44:21,851 - DEBUG - VN service-left-vn-19599250 UUID is ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:21,919 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:21,925 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:21,935 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:22,032 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,050 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,067 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:22,076 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:22,083 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/783b3c28-8d74-4d06-b820-3802811726a7
2025-10-06 02:44:22,094 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:22,095 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,104 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:22,121 - INFO - Verified VN network id 11 for VN ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,121 - INFO - Verifications in API Server for VN service-left-vn-19599250 passed
2025-10-06 02:44:22,121 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,132 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:22,139 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:22,145 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/783b3c28-8d74-4d06-b820-3802811726a7
2025-10-06 02:44:22,157 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12529752524322523892', 'uuid-lslong': '13127892414455616780'}, 'enable': 'true', 'created': '2025-10-06T02:44:21', 'last-modified': '2025-10-06T02:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389289'}
2025-10-06 02:44:22,163 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:22,169 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12529752524322523892', 'uuid-lslong': '13127892414455616780'}, 'enable': 'true', 'created': '2025-10-06T02:44:21', 'last-modified': '2025-10-06T02:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403993'}
2025-10-06 02:44:22,175 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:22,182 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12529752524322523892', 'uuid-lslong': '13127892414455616780'}, 'enable': 'true', 'created': '2025-10-06T02:44:21', 'last-modified': '2025-10-06T02:44:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413836'}
2025-10-06 02:44:22,188 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:22,188 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-19599250 passed
2025-10-06 02:44:22,189 - DEBUG - ====Verifying policy data for service-left-vn-19599250 in API_Server ======
2025-10-06 02:44:22,189 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:22,195 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:22,205 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:22,293 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/ade29f89-0fcc-4af4-b62f-a498ad2c150c
2025-10-06 02:44:22,302 - DEBUG - =>VN service-left-vn-19599250 has no policy to be verified
2025-10-06 02:44:22,302 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:22,302 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:22,302 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:22,351 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250?flat'}
2025-10-06 02:44:22,351 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:22,351 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:22,403 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250?flat'}
2025-10-06 02:44:22,403 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:22,403 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:22,455 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250?flat'}
2025-10-06 02:44:22,455 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:service-left-vn-19599250 is found in opserver
2025-10-06 02:44:22,474 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:22,489 - DEBUG - VRF ids for VN service-left-vn-19599250: {}
2025-10-06 02:44:22,768 - DEBUG - Response for create_network : {'network': {'id': '6f08fa57-df7f-4ef0-b713-c4bffd9d9582', 'name': 'service-right-vn-66855572', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'service-right-vn-66855572'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:23,046 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4db18db-e148-4ed9-b7c7-3ed39641ad7f', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': '6f08fa57-df7f-4ef0-b713-c4bffd9d9582', 'ip_version': 4, 'cidr': '35.214.80.128/26', 'allocation_pools': [{'start': '35.214.80.130', 'end': '35.214.80.190'}], 'gateway_ip': '35.214.80.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '35.214.80.130', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:23,068 - INFO - Created VN service-right-vn-66855572
2025-10-06 02:44:23,081 - DEBUG - VN service-right-vn-66855572 UUID is 6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,142 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:23,147 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:23,156 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:23,243 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,256 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,264 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:23,270 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:23,276 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e05ea36d-8dda-4c45-91eb-3fe252c6e537
2025-10-06 02:44:23,282 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:23,282 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,289 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:23,302 - INFO - Verified VN network id 12 for VN 6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,302 - INFO - Verifications in API Server for VN service-right-vn-66855572 passed
2025-10-06 02:44:23,303 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,312 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:23,325 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:23,351 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e05ea36d-8dda-4c45-91eb-3fe252c6e537
2025-10-06 02:44:23,385 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8000919993342447344', 'uuid-lslong': '13192104062371075458'}, 'enable': 'true', 'created': '2025-10-06T02:44:22', 'last-modified': '2025-10-06T02:44:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.386693'}
2025-10-06 02:44:23,391 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:23,397 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8000919993342447344', 'uuid-lslong': '13192104062371075458'}, 'enable': 'true', 'created': '2025-10-06T02:44:22', 'last-modified': '2025-10-06T02:44:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408020'}
2025-10-06 02:44:23,402 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:23,408 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8000919993342447344', 'uuid-lslong': '13192104062371075458'}, 'enable': 'true', 'created': '2025-10-06T02:44:22', 'last-modified': '2025-10-06T02:44:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406862'}
2025-10-06 02:44:23,414 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:23,414 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-66855572 passed
2025-10-06 02:44:23,415 - DEBUG - ====Verifying policy data for service-right-vn-66855572 in API_Server ======
2025-10-06 02:44:23,415 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:23,421 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:23,432 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:23,522 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/6f08fa57-df7f-4ef0-b713-c4bffd9d9582
2025-10-06 02:44:23,532 - DEBUG - =>VN service-right-vn-66855572 has no policy to be verified
2025-10-06 02:44:23,532 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:23,532 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:23,532 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:23,589 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572?flat'}
2025-10-06 02:44:23,589 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:23,590 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:23,642 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572?flat'}
2025-10-06 02:44:23,642 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:23,642 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:23,691 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572?flat'}
2025-10-06 02:44:23,691 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:service-right-vn-66855572 is found in opserver
2025-10-06 02:44:23,711 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:23,719 - DEBUG - VRF ids for VN service-right-vn-66855572: {}
2025-10-06 02:44:23,770 - DEBUG - Services list from nova: [, , , ]
2025-10-06 02:44:24,740 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-1)
2025-10-06 02:44:25,960 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-2)
2025-10-06 02:44:25,960 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-32998687']
2025-10-06 02:44:26,260 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-32998687'] created succesfully
2025-10-06 02:44:26,618 - DEBUG - Image by name tiny_nat_fw not found
2025-10-06 02:44:26,618 - DEBUG - Installing image tiny_nat_fw
2025-10-06 02:44:26,618 - 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-10-06 02:44:26,618 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:26,618 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:26,619 - DEBUG - Unzip image
2025-10-06 02:44:26,619 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:26,636 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-06 02:44:26,637 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-32998687']
2025-10-06 02:44:26,729 - INFO - Service template: ['default-domain', 'ctest-service_template_1-32998687'] deleted successfully.
2025-10-06 02:44:26,729 - INFO - Skip interface_detach for VM ctest-right_vm-98132779 in state BUILD
2025-10-06 02:44:26,729 - INFO - Deleting VM ctest-right_vm-98132779
2025-10-06 02:44:26,757 - ERROR - VM ctest-right_vm-98132779 has failed to come up
2025-10-06 02:44:26,757 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:26,757 - ERROR - VM ctest-right_vm-98132779 has failed to come up
2025-10-06 02:44:26,757 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:26,874 - INFO - Skip interface_detach for VM ctest-left_vm-05287061 in state BUILD
2025-10-06 02:44:26,874 - INFO - Deleting VM ctest-left_vm-05287061
2025-10-06 02:44:26,874 - ERROR - VM ctest-left_vm-05287061 has failed to come up
2025-10-06 02:44:26,874 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:26,874 - ERROR - VM ctest-left_vm-05287061 has failed to come up
2025-10-06 02:44:26,874 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:26,971 - INFO - Deleting VN service-right-vn-66855572
2025-10-06 02:44:27,018 - DEBUG - VN 6f08fa57-df7f-4ef0-b713-c4bffd9d9582 still in use: Unable to complete operation on network 6f08fa57-df7f-4ef0-b713-c4bffd9d9582. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-870a4bf4-631f-4437-b80c-d6040ca4e461']
2025-10-06 02:44:27,018 - WARNING - Deleting VN service-right-vn-66855572 failed..Will retry
2025-10-06 02:44:29,195 - DEBUG - Response for deleting network ()
2025-10-06 02:44:29,195 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/d2e816bc-461c-4340-a136-525a041d1937
2025-10-06 02:44:29,201 - DEBUG - Response Code: 404
2025-10-06 02:44:29,201 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:29,207 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:29,235 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:29,318 - INFO - Validated that VN service-right-vn-66855572 is not found in API Server
2025-10-06 02:44:29,329 - DEBUG - VN service-right-vn-66855572 is not present in Agent 10.0.0.49
2025-10-06 02:44:29,338 - DEBUG - VN service-right-vn-66855572 is not present in Agent 10.0.0.19
2025-10-06 02:44:29,338 - INFO - Validated that VN service-right-vn-66855572 is not in any agent
2025-10-06 02:44:29,363 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-66855572 info
2025-10-06 02:44:29,363 - INFO - Deleting VN service-left-vn-19599250
2025-10-06 02:44:29,499 - DEBUG - Response for deleting network ()
2025-10-06 02:44:29,499 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/5e0e3f60-c471-4045-8437-d621f45db085
2025-10-06 02:44:29,510 - DEBUG - Response Code: 404
2025-10-06 02:44:29,510 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:29,516 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:29,526 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:29,607 - INFO - Validated that VN service-left-vn-19599250 is not found in API Server
2025-10-06 02:44:29,618 - DEBUG - VN service-left-vn-19599250 is not present in Agent 10.0.0.49
2025-10-06 02:44:29,629 - DEBUG - VN service-left-vn-19599250 is not present in Agent 10.0.0.19
2025-10-06 02:44:29,629 - INFO - Validated that VN service-left-vn-19599250 is not in any agent
2025-10-06 02:44:29,657 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-19599250 info
2025-10-06 02:44:29,657 - INFO - Deleting VN ctest-mgmt_vn-99603356
2025-10-06 02:44:29,776 - DEBUG - Response for deleting network ()
2025-10-06 02:44:29,777 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/7d239a7d-0898-427a-8316-60341a7892d9
2025-10-06 02:44:29,784 - DEBUG - Response Code: 404
2025-10-06 02:44:29,784 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:29,799 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:29,854 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:29,944 - INFO - Validated that VN ctest-mgmt_vn-99603356 is not found in API Server
2025-10-06 02:44:29,953 - DEBUG - VN ctest-mgmt_vn-99603356 is not present in Agent 10.0.0.49
2025-10-06 02:44:29,962 - DEBUG - VN ctest-mgmt_vn-99603356 is not present in Agent 10.0.0.19
2025-10-06 02:44:29,962 - INFO - Validated that VN ctest-mgmt_vn-99603356 is not in any agent
2025-10-06 02:44:29,990 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-99603356 info
2025-10-06 02:44:31,250 - DEBUG - No XMPP flaps were noticed during the test
2025-10-06 02:44:31,250 - INFO -
2025-10-06 02:44:31,250 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2025-10-06 02:44:31,250 - INFO - --------------------------------------------------------------------------------
2025-10-06 02:44:31,253 - INFO - ================================================================================
2025-10-06 02:44:31,253 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-06 02:44:32,502 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.193': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.25': '0', '10.20.0.17': '0'}} with
2025-10-06 02:44:32,502 - INFO - Initial checks done. Running the testcase now
2025-10-06 02:44:32,502 - INFO -
2025-10-06 02:44:32,784 - DEBUG - Response for create_network : {'network': {'id': 'efaa046b-8c56-4058-8310-174cd433247b', 'name': 'ctest-mgmt_vn-80137358', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'ctest-mgmt_vn-80137358'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:32,995 - DEBUG - Response for create_subnet : {'subnet': {'id': 'efcc8c58-364e-4167-8ab0-e05dd795d106', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': 'efaa046b-8c56-4058-8310-174cd433247b', 'ip_version': 4, 'cidr': '151.27.209.64/26', 'allocation_pools': [{'start': '151.27.209.66', 'end': '151.27.209.126'}], 'gateway_ip': '151.27.209.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '151.27.209.66', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:33,016 - INFO - Created VN ctest-mgmt_vn-80137358
2025-10-06 02:44:33,037 - DEBUG - VN ctest-mgmt_vn-80137358 UUID is efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,113 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:33,118 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:33,129 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:33,220 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,231 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,239 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:33,246 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:33,252 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/dbbe082d-1553-476f-aad9-b189572e22d7
2025-10-06 02:44:33,258 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:33,258 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,267 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:33,282 - INFO - Verified VN network id 10 for VN efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,282 - INFO - Verifications in API Server for VN ctest-mgmt_vn-80137358 passed
2025-10-06 02:44:33,282 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,290 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:33,296 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:33,303 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/dbbe082d-1553-476f-aad9-b189572e22d7
2025-10-06 02:44:33,314 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17269620581068062808', 'uuid-lslong': '9444074037341004923'}, 'enable': 'true', 'created': '2025-10-06T02:44:32', 'last-modified': '2025-10-06T02:44:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.362997'}
2025-10-06 02:44:33,321 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:33,325 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17269620581068062808', 'uuid-lslong': '9444074037341004923'}, 'enable': 'true', 'created': '2025-10-06T02:44:32', 'last-modified': '2025-10-06T02:44:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381291'}
2025-10-06 02:44:33,330 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:33,335 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17269620581068062808', 'uuid-lslong': '9444074037341004923'}, 'enable': 'true', 'created': '2025-10-06T02:44:32', 'last-modified': '2025-10-06T02:44:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.386184'}
2025-10-06 02:44:33,341 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-06 02:44:33,342 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-80137358 passed
2025-10-06 02:44:33,342 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-80137358 in API_Server ======
2025-10-06 02:44:33,342 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:33,347 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:33,357 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:33,447 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/efaa046b-8c56-4058-8310-174cd433247b
2025-10-06 02:44:33,455 - DEBUG - =>VN ctest-mgmt_vn-80137358 has no policy to be verified
2025-10-06 02:44:33,455 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:33,455 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:33,456 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:33,468 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358?flat'}
2025-10-06 02:44:33,468 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:33,468 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:33,480 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358?flat'}
2025-10-06 02:44:33,480 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:33,480 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:33,491 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358?flat'}
2025-10-06 02:44:33,492 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:ctest-mgmt_vn-80137358 is found in opserver
2025-10-06 02:44:33,512 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:33,521 - DEBUG - VRF ids for VN ctest-mgmt_vn-80137358: {}
2025-10-06 02:44:33,773 - DEBUG - Response for create_network : {'network': {'id': '1dfb2532-3904-4a54-a3d2-c123263fd5dc', 'name': 'ctest-left_vn-90056395', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'ctest-left_vn-90056395'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:34,007 - DEBUG - Response for create_subnet : {'subnet': {'id': 'edc53ea2-d6ff-4b5a-8c47-18b46ce08569', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': '1dfb2532-3904-4a54-a3d2-c123263fd5dc', 'ip_version': 4, 'cidr': '190.76.248.64/26', 'allocation_pools': [{'start': '190.76.248.66', 'end': '190.76.248.126'}], 'gateway_ip': '190.76.248.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '190.76.248.66', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:34,031 - INFO - Created VN ctest-left_vn-90056395
2025-10-06 02:44:34,044 - DEBUG - VN ctest-left_vn-90056395 UUID is 1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,113 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:34,119 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:34,131 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:34,219 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,232 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,241 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:34,250 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:34,257 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/79a5abd2-7c0b-4787-9dac-f25eb1b5fb02
2025-10-06 02:44:34,263 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:34,263 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,272 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:34,285 - INFO - Verified VN network id 11 for VN 1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,286 - INFO - Verifications in API Server for VN ctest-left_vn-90056395 passed
2025-10-06 02:44:34,286 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,296 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:34,302 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:34,310 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/79a5abd2-7c0b-4787-9dac-f25eb1b5fb02
2025-10-06 02:44:34,322 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2160361343889459796', 'uuid-lslong': '11804709930001225180'}, 'enable': 'true', 'created': '2025-10-06T02:44:33', 'last-modified': '2025-10-06T02:44:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.352135'}
2025-10-06 02:44:34,330 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:34,334 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2160361343889459796', 'uuid-lslong': '11804709930001225180'}, 'enable': 'true', 'created': '2025-10-06T02:44:33', 'last-modified': '2025-10-06T02:44:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369843'}
2025-10-06 02:44:34,340 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:34,346 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2160361343889459796', 'uuid-lslong': '11804709930001225180'}, 'enable': 'true', 'created': '2025-10-06T02:44:33', 'last-modified': '2025-10-06T02:44:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.379233'}
2025-10-06 02:44:34,352 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-06 02:44:34,352 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-90056395 passed
2025-10-06 02:44:34,352 - DEBUG - ====Verifying policy data for ctest-left_vn-90056395 in API_Server ======
2025-10-06 02:44:34,352 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:34,358 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:34,368 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:34,458 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1dfb2532-3904-4a54-a3d2-c123263fd5dc
2025-10-06 02:44:34,468 - DEBUG - =>VN ctest-left_vn-90056395 has no policy to be verified
2025-10-06 02:44:34,468 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:34,468 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:34,468 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:34,479 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395?flat'}
2025-10-06 02:44:34,479 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:34,479 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:34,490 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395?flat'}
2025-10-06 02:44:34,490 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:34,490 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:34,504 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395?flat'}
2025-10-06 02:44:34,504 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:ctest-left_vn-90056395 is found in opserver
2025-10-06 02:44:34,528 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:34,538 - DEBUG - VRF ids for VN ctest-left_vn-90056395: {}
2025-10-06 02:44:34,765 - DEBUG - Response for create_network : {'network': {'id': '24065653-f11d-4c16-b862-b1d2a93690ec', 'name': 'ctest-right_vn-76565352', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'project_id': '69872beabc4c4b998ed505bcb2957626', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58777482', 'ctest-right_vn-76565352'], 'port_security_enabled': True, 'description': ''}}
2025-10-06 02:44:34,995 - DEBUG - Response for create_subnet : {'subnet': {'id': '1ce2a73a-07d2-4a90-8b0c-00f3feabd306', 'name': '', 'tenant_id': '69872beabc4c4b998ed505bcb2957626', 'network_id': '24065653-f11d-4c16-b862-b1d2a93690ec', 'ip_version': 4, 'cidr': '206.59.34.64/26', 'allocation_pools': [{'start': '206.59.34.66', 'end': '206.59.34.126'}], 'gateway_ip': '206.59.34.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '206.59.34.66', 'tags': [], 'project_id': '69872beabc4c4b998ed505bcb2957626'}}
2025-10-06 02:44:35,014 - INFO - Created VN ctest-right_vn-76565352
2025-10-06 02:44:35,028 - DEBUG - VN ctest-right_vn-76565352 UUID is 24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,096 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:35,101 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:35,111 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:35,201 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,216 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,224 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:35,230 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:35,236 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0dfdf865-e577-40e1-baae-ecb32194a1d2
2025-10-06 02:44:35,242 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:35,242 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,250 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:35,264 - INFO - Verified VN network id 12 for VN 24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,264 - INFO - Verifications in API Server for VN ctest-right_vn-76565352 passed
2025-10-06 02:44:35,264 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,272 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:35,278 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:35,284 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0dfdf865-e577-40e1-baae-ecb32194a1d2
2025-10-06 02:44:35,295 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2595857153753173014', 'uuid-lslong': '13286377369036558572'}, 'enable': 'true', 'created': '2025-10-06T02:44:34', 'last-modified': '2025-10-06T02:44:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.361414'}
2025-10-06 02:44:35,301 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:35,308 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2595857153753173014', 'uuid-lslong': '13286377369036558572'}, 'enable': 'true', 'created': '2025-10-06T02:44:34', 'last-modified': '2025-10-06T02:44:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.376899'}
2025-10-06 02:44:35,314 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:35,319 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58777482', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2595857153753173014', 'uuid-lslong': '13286377369036558572'}, 'enable': 'true', 'created': '2025-10-06T02:44:34', 'last-modified': '2025-10-06T02:44:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389239'}
2025-10-06 02:44:35,324 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-06 02:44:35,324 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-76565352 passed
2025-10-06 02:44:35,325 - DEBUG - ====Verifying policy data for ctest-right_vn-76565352 in API_Server ======
2025-10-06 02:44:35,325 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:35,330 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:35,340 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:35,425 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/24065653-f11d-4c16-b862-b1d2a93690ec
2025-10-06 02:44:35,434 - DEBUG - =>VN ctest-right_vn-76565352 has no policy to be verified
2025-10-06 02:44:35,434 - DEBUG - Verifying the vn in opserver
2025-10-06 02:44:35,434 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352 virtual network link through opserver 10.0.0.33
2025-10-06 02:44:35,434 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2025-10-06 02:44:35,445 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352?flat'}
2025-10-06 02:44:35,445 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352 virtual network link through opserver 10.0.0.38
2025-10-06 02:44:35,445 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-10-06 02:44:35,456 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352?flat'}
2025-10-06 02:44:35,456 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352 virtual network link through opserver 10.0.0.249
2025-10-06 02:44:35,456 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2025-10-06 02:44:35,468 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352?flat'}
2025-10-06 02:44:35,469 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58777482:ctest-right_vn-76565352 is found in opserver
2025-10-06 02:44:35,488 - DEBUG - Do not have enough data to verify VN in agent
2025-10-06 02:44:35,497 - DEBUG - VRF ids for VN ctest-right_vn-76565352: {}
2025-10-06 02:44:36,517 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-2)
2025-10-06 02:44:37,899 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4219-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4219-1)
2025-10-06 02:44:37,899 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-48850952']
2025-10-06 02:44:38,192 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-48850952'] created succesfully
2025-10-06 02:44:38,544 - DEBUG - Image by name tiny_nat_fw not found
2025-10-06 02:44:38,544 - DEBUG - Installing image tiny_nat_fw
2025-10-06 02:44:38,544 - 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-10-06 02:44:38,544 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:38,544 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:38,544 - DEBUG - Unzip image
2025-10-06 02:44:38,544 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-06 02:44:38,575 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-06 02:44:38,577 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-48850952']
2025-10-06 02:44:38,674 - INFO - Service template: ['default-domain', 'ctest-service_template_1-48850952'] deleted successfully.
2025-10-06 02:44:38,674 - INFO - Skip interface_detach for VM ctest-right_vm-51774821 in state BUILD
2025-10-06 02:44:38,674 - INFO - Deleting VM ctest-right_vm-51774821
2025-10-06 02:44:38,675 - ERROR - VM ctest-right_vm-51774821 has failed to come up
2025-10-06 02:44:38,675 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:38,675 - ERROR - VM ctest-right_vm-51774821 has failed to come up
2025-10-06 02:44:38,675 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:38,764 - INFO - Skip interface_detach for VM ctest-left_vm-69083932 in state BUILD
2025-10-06 02:44:38,764 - INFO - Deleting VM ctest-left_vm-69083932
2025-10-06 02:44:38,764 - ERROR - VM ctest-left_vm-69083932 has failed to come up
2025-10-06 02:44:38,764 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:38,764 - ERROR - VM ctest-left_vm-69083932 has failed to come up
2025-10-06 02:44:38,764 - ERROR - Nova failed to get host of the VM
2025-10-06 02:44:38,845 - INFO - Deleting VN ctest-right_vn-76565352
2025-10-06 02:44:38,901 - DEBUG - VN 24065653-f11d-4c16-b862-b1d2a93690ec still in use: Unable to complete operation on network 24065653-f11d-4c16-b862-b1d2a93690ec. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-34452998-5c5c-4ec8-a81a-16dd76c48303']
2025-10-06 02:44:38,901 - WARNING - Deleting VN ctest-right_vn-76565352 failed..Will retry
2025-10-06 02:44:41,078 - DEBUG - Response for deleting network ()
2025-10-06 02:44:41,078 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/3ca8682d-4132-4e6f-b988-05b359bcf928
2025-10-06 02:44:41,088 - DEBUG - Response Code: 404
2025-10-06 02:44:41,088 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:41,094 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:41,148 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:41,249 - INFO - Validated that VN ctest-right_vn-76565352 is not found in API Server
2025-10-06 02:44:41,261 - DEBUG - VN ctest-right_vn-76565352 is not present in Agent 10.0.0.49
2025-10-06 02:44:41,273 - DEBUG - VN ctest-right_vn-76565352 is not present in Agent 10.0.0.19
2025-10-06 02:44:41,273 - INFO - Validated that VN ctest-right_vn-76565352 is not in any agent
2025-10-06 02:44:41,302 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-76565352 info
2025-10-06 02:44:41,302 - INFO - Deleting VN ctest-left_vn-90056395
2025-10-06 02:44:41,450 - DEBUG - Response for deleting network ()
2025-10-06 02:44:41,450 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/21cb4f3b-6495-48b0-8f68-a2009ee76ebb
2025-10-06 02:44:41,467 - DEBUG - Response Code: 404
2025-10-06 02:44:41,467 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:41,476 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:41,534 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:41,632 - INFO - Validated that VN ctest-left_vn-90056395 is not found in API Server
2025-10-06 02:44:41,642 - DEBUG - VN ctest-left_vn-90056395 is not present in Agent 10.0.0.49
2025-10-06 02:44:41,653 - DEBUG - VN ctest-left_vn-90056395 is not present in Agent 10.0.0.19
2025-10-06 02:44:41,653 - INFO - Validated that VN ctest-left_vn-90056395 is not in any agent
2025-10-06 02:44:41,692 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-90056395 info
2025-10-06 02:44:41,692 - INFO - Deleting VN ctest-mgmt_vn-80137358
2025-10-06 02:44:41,841 - DEBUG - Response for deleting network ()
2025-10-06 02:44:41,842 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/20f3cc3b-9145-4fe2-a7dc-c2492cdf89e4
2025-10-06 02:44:41,848 - DEBUG - Response Code: 404
2025-10-06 02:44:41,848 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2025-10-06 02:44:41,853 - DEBUG - Requesting: http://10.0.0.33:8082/domain/8177ca4a-5181-4745-ae27-f33c0972392c
2025-10-06 02:44:41,876 - DEBUG - Requesting: http://10.0.0.33:8082/project/69872bea-bc4c-4b99-8ed5-05bcb2957626
2025-10-06 02:44:41,964 - INFO - Validated that VN ctest-mgmt_vn-80137358 is not found in API Server
2025-10-06 02:44:41,975 - DEBUG - VN ctest-mgmt_vn-80137358 is not present in Agent 10.0.0.49
2025-10-06 02:44:41,986 - DEBUG - VN ctest-mgmt_vn-80137358 is not present in Agent 10.0.0.19
2025-10-06 02:44:41,986 - INFO - Validated that VN ctest-mgmt_vn-80137358 is not in any agent
2025-10-06 02:44:42,018 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-80137358 info
2025-10-06 02:44:43,279 - DEBUG - No XMPP flaps were noticed during the test
2025-10-06 02:44:43,279 - INFO -
2025-10-06 02:44:43,279 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-06 02:44:43,279 - INFO - --------------------------------------------------------------------------------
2025-10-06 02:44:44,083 - INFO - Deleted project: ctest-TestSvcRegr-58777482, ID : 69872bea-bc4c-4b99-8ed5-05bcb2957626