]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1)
2026-02-09 03:06:30,058 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-55932524']
2026-02-09 03:06:30,349 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-55932524'] created succesfully
2026-02-09 03:06:30,716 - DEBUG - Image by name tiny_nat_fw not found
2026-02-09 03:06:30,718 - DEBUG - Installing image tiny_nat_fw
2026-02-09 03:06:30,718 - 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'"}
2026-02-09 03:06:30,718 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-02-09 03:06:30,718 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2026-02-09 03:06:30,738 - 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
2026-02-09 03:06:30,892 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:06:30,893 - DEBUG - Unzip image
2026-02-09 03:06:30,893 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:06:30,912 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-02-09 03:06:30,913 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-55932524']
2026-02-09 03:06:30,998 - INFO - Service template: ['default-domain', 'ctest-service_template_1-55932524'] deleted successfully.
2026-02-09 03:06:30,999 - INFO - Skip interface_detach for VM ctest-right_vm-54522736 in state BUILD
2026-02-09 03:06:30,999 - INFO - Deleting VM ctest-right_vm-54522736
2026-02-09 03:06:31,025 - ERROR - VM ctest-right_vm-54522736 has failed to come up
2026-02-09 03:06:31,026 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:31,026 - ERROR - VM ctest-right_vm-54522736 has failed to come up
2026-02-09 03:06:31,026 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:31,102 - INFO - Skip interface_detach for VM ctest-left_vm-55884197 in state BUILD
2026-02-09 03:06:31,102 - INFO - Deleting VM ctest-left_vm-55884197
2026-02-09 03:06:31,102 - ERROR - VM ctest-left_vm-55884197 has failed to come up
2026-02-09 03:06:31,102 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:31,102 - ERROR - VM ctest-left_vm-55884197 has failed to come up
2026-02-09 03:06:31,102 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:31,181 - INFO - Deleting VN ctest-right_vn-42171667
2026-02-09 03:06:31,248 - DEBUG - VN 5663e021-158e-43a6-9b03-c69393844174 still in use: Unable to complete operation on network 5663e021-158e-43a6-9b03-c69393844174. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-5a2bc7e2-c4a6-4d5a-92ec-c91aef8a39c0']
2026-02-09 03:06:31,248 - WARNING - Deleting VN ctest-right_vn-42171667 failed..Will retry
2026-02-09 03:06:33,388 - DEBUG - Response for deleting network ()
2026-02-09 03:06:33,388 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/a74ff995-cf9a-4e3a-9f8c-eb357b4f3d49
2026-02-09 03:06:33,393 - DEBUG - Response Code: 404
2026-02-09 03:06:33,393 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:33,407 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:33,470 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:33,567 - INFO - Validated that VN ctest-right_vn-42171667 is not found in API Server
2026-02-09 03:06:33,578 - DEBUG - VN ctest-right_vn-42171667 is not present in Agent 10.0.0.27
2026-02-09 03:06:33,588 - DEBUG - VN ctest-right_vn-42171667 is not present in Agent 10.0.0.65
2026-02-09 03:06:33,588 - INFO - Validated that VN ctest-right_vn-42171667 is not in any agent
2026-02-09 03:06:33,614 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-42171667 info
2026-02-09 03:06:33,615 - INFO - Deleting VN ctest-left_vn-61513483
2026-02-09 03:06:33,773 - DEBUG - Response for deleting network ()
2026-02-09 03:06:33,773 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/3e409cd0-4dd6-4e95-a493-7fe1befe3924
2026-02-09 03:06:33,779 - DEBUG - Response Code: 404
2026-02-09 03:06:33,779 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:33,788 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:33,841 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:33,930 - INFO - Validated that VN ctest-left_vn-61513483 is not found in API Server
2026-02-09 03:06:33,941 - DEBUG - VN ctest-left_vn-61513483 is not present in Agent 10.0.0.27
2026-02-09 03:06:33,953 - DEBUG - VN ctest-left_vn-61513483 is not present in Agent 10.0.0.65
2026-02-09 03:06:33,953 - INFO - Validated that VN ctest-left_vn-61513483 is not in any agent
2026-02-09 03:06:33,986 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-61513483 info
2026-02-09 03:06:33,986 - INFO - Deleting VN ctest-mgmt_vn-55581330
2026-02-09 03:06:34,195 - DEBUG - Response for deleting network ()
2026-02-09 03:06:34,195 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/99da6943-7bf1-4dc4-ab98-13112cd425aa
2026-02-09 03:06:34,199 - DEBUG - Response Code: 404
2026-02-09 03:06:34,200 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:34,218 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:34,228 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:34,330 - INFO - Validated that VN ctest-mgmt_vn-55581330 is not found in API Server
2026-02-09 03:06:34,345 - DEBUG - VN ctest-mgmt_vn-55581330 is not present in Agent 10.0.0.27
2026-02-09 03:06:34,356 - DEBUG - VN ctest-mgmt_vn-55581330 is not present in Agent 10.0.0.65
2026-02-09 03:06:34,356 - INFO - Validated that VN ctest-mgmt_vn-55581330 is not in any agent
2026-02-09 03:06:34,396 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-55581330 info
2026-02-09 03:06:34,396 - INFO - Deleting VM ctest-vm-in-third-vn-41558277
2026-02-09 03:06:34,502 - INFO - Deleting VN ctest-third-vn-60357039
2026-02-09 03:06:34,554 - DEBUG - VN 2e59c226-b8ef-43fb-8add-5f93da4e2f31 still in use: Unable to complete operation on network 2e59c226-b8ef-43fb-8add-5f93da4e2f31. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9f0ba888-f182-4c8a-91c2-986d4ebeaa1f']
2026-02-09 03:06:34,554 - WARNING - Deleting VN ctest-third-vn-60357039 failed..Will retry
2026-02-09 03:06:36,701 - DEBUG - Response for deleting network ()
2026-02-09 03:06:37,998 - DEBUG - No XMPP flaps were noticed during the test
2026-02-09 03:06:37,999 - INFO -
2026-02-09 03:06:37,999 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:23]
2026-02-09 03:06:37,999 - INFO - --------------------------------------------------------------------------------
2026-02-09 03:06:38,002 - INFO - ================================================================================
2026-02-09 03:06:38,002 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2026-02-09 03:06:39,285 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.18': '0', '10.20.0.25': '0'}} with
2026-02-09 03:06:39,286 - INFO - Initial checks done. Running the testcase now
2026-02-09 03:06:39,286 - INFO -
2026-02-09 03:06:39,550 - DEBUG - Response for create_network : {'network': {'id': '94509362-83d3-40ad-bf65-28ba86294e54', 'name': 'ctest-mgmt_vn-94476554', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-65696099', 'ctest-mgmt_vn-94476554'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:39,787 - DEBUG - Response for create_subnet : {'subnet': {'id': '26aa75bf-27d1-43fd-b6fe-85373ab7c3a9', 'name': '', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'network_id': '94509362-83d3-40ad-bf65-28ba86294e54', 'ip_version': 4, 'cidr': '114.181.92.128/26', 'allocation_pools': [{'start': '114.181.92.130', 'end': '114.181.92.190'}], 'gateway_ip': '114.181.92.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.181.92.130', 'tags': [], 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f'}}
2026-02-09 03:06:39,814 - INFO - Created VN ctest-mgmt_vn-94476554
2026-02-09 03:06:39,832 - DEBUG - VN ctest-mgmt_vn-94476554 UUID is 94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:39,899 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:39,905 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:39,917 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:40,003 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,015 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,023 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:40,030 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:40,036 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/a5937132-3449-461d-9763-cae5cf8275cd
2026-02-09 03:06:40,043 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:40,043 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,050 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:40,066 - INFO - Verified VN network id 15 for VN 94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,066 - INFO - Verifications in API Server for VN ctest-mgmt_vn-94476554 passed
2026-02-09 03:06:40,066 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,074 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:40,079 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:40,085 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/a5937132-3449-461d-9763-cae5cf8275cd
2026-02-09 03:06:40,095 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10687203967077925037', 'uuid-lslong': '13791474215471894100'}, 'enable': 'true', 'created': '2026-02-09T03:06:39', 'last-modified': '2026-02-09T03:06:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.354953'}
2026-02-09 03:06:40,100 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:40,105 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10687203967077925037', 'uuid-lslong': '13791474215471894100'}, 'enable': 'true', 'created': '2026-02-09T03:06:39', 'last-modified': '2026-02-09T03:06:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.361625'}
2026-02-09 03:06:40,110 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:40,115 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10687203967077925037', 'uuid-lslong': '13791474215471894100'}, 'enable': 'true', 'created': '2026-02-09T03:06:39', 'last-modified': '2026-02-09T03:06:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.376041'}
2026-02-09 03:06:40,121 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:40,121 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-94476554 passed
2026-02-09 03:06:40,121 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-94476554 in API_Server ======
2026-02-09 03:06:40,121 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:40,126 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:40,136 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:40,228 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/94509362-83d3-40ad-bf65-28ba86294e54
2026-02-09 03:06:40,240 - DEBUG - =>VN ctest-mgmt_vn-94476554 has no policy to be verified
2026-02-09 03:06:40,241 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:40,241 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:40,241 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:40,252 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554?flat'}
2026-02-09 03:06:40,252 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:40,252 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:40,266 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554?flat'}
2026-02-09 03:06:40,266 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:40,266 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:06:40,280 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554?flat'}
2026-02-09 03:06:40,280 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-65696099:ctest-mgmt_vn-94476554 is found in opserver
2026-02-09 03:06:40,303 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:06:40,313 - DEBUG - VRF ids for VN ctest-mgmt_vn-94476554: {}
2026-02-09 03:06:40,568 - DEBUG - Response for create_network : {'network': {'id': '37dbc672-cc3f-4dcf-94be-131c671bc324', 'name': 'ctest-left_vn-11149586', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-65696099', 'ctest-left_vn-11149586'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:40,785 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f673987b-389c-4f82-af4b-f6b01b4dc176', 'name': '', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'network_id': '37dbc672-cc3f-4dcf-94be-131c671bc324', 'ip_version': 4, 'cidr': '194.73.178.128/26', 'allocation_pools': [{'start': '194.73.178.130', 'end': '194.73.178.190'}], 'gateway_ip': '194.73.178.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '194.73.178.130', 'tags': [], 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f'}}
2026-02-09 03:06:40,810 - INFO - Created VN ctest-left_vn-11149586
2026-02-09 03:06:40,821 - DEBUG - VN ctest-left_vn-11149586 UUID is 37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:40,888 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:40,894 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:40,904 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:40,994 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,007 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,016 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:41,024 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:41,031 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/f980f3e9-8e72-4ae9-8feb-bc0038ed67ee
2026-02-09 03:06:41,036 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:06:41,036 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,044 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:41,058 - INFO - Verified VN network id 16 for VN 37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,059 - INFO - Verifications in API Server for VN ctest-left_vn-11149586 passed
2026-02-09 03:06:41,059 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,066 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:41,079 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:41,094 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/f980f3e9-8e72-4ae9-8feb-bc0038ed67ee
2026-02-09 03:06:41,112 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4025028888340942287', 'uuid-lslong': '10718025175898243876'}, 'enable': 'true', 'created': '2026-02-09T03:06:40', 'last-modified': '2026-02-09T03:06:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.361744'}
2026-02-09 03:06:41,128 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:06:41,134 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4025028888340942287', 'uuid-lslong': '10718025175898243876'}, 'enable': 'true', 'created': '2026-02-09T03:06:40', 'last-modified': '2026-02-09T03:06:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414255'}
2026-02-09 03:06:41,143 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:06:41,155 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4025028888340942287', 'uuid-lslong': '10718025175898243876'}, 'enable': 'true', 'created': '2026-02-09T03:06:40', 'last-modified': '2026-02-09T03:06:40', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428961'}
2026-02-09 03:06:41,169 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:06:41,169 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-11149586 passed
2026-02-09 03:06:41,169 - DEBUG - ====Verifying policy data for ctest-left_vn-11149586 in API_Server ======
2026-02-09 03:06:41,169 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:41,179 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:41,191 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:41,297 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/37dbc672-cc3f-4dcf-94be-131c671bc324
2026-02-09 03:06:41,311 - DEBUG - =>VN ctest-left_vn-11149586 has no policy to be verified
2026-02-09 03:06:41,311 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:41,311 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:41,311 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:41,324 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586?flat'}
2026-02-09 03:06:41,324 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:41,324 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:41,339 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586?flat'}
2026-02-09 03:06:41,339 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:41,339 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:06:41,351 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586?flat'}
2026-02-09 03:06:41,351 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-65696099:ctest-left_vn-11149586 is found in opserver
2026-02-09 03:06:41,371 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:06:41,381 - DEBUG - VRF ids for VN ctest-left_vn-11149586: {}
2026-02-09 03:06:41,639 - DEBUG - Response for create_network : {'network': {'id': 'd6eb4f98-55a0-411f-9e56-d8c501e57789', 'name': 'ctest-right_vn-74394428', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-65696099', 'ctest-right_vn-74394428'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:41,932 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c002c59a-40a5-4fea-829a-685c0ac81e41', 'name': '', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'network_id': 'd6eb4f98-55a0-411f-9e56-d8c501e57789', 'ip_version': 4, 'cidr': '206.221.88.128/26', 'allocation_pools': [{'start': '206.221.88.130', 'end': '206.221.88.190'}], 'gateway_ip': '206.221.88.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '206.221.88.130', 'tags': [], 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f'}}
2026-02-09 03:06:41,952 - INFO - Created VN ctest-right_vn-74394428
2026-02-09 03:06:41,963 - DEBUG - VN ctest-right_vn-74394428 UUID is d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,029 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:42,034 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:42,044 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:42,127 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,144 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,154 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:42,160 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:42,165 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/f22973fd-3c9d-46f8-9e91-ac4b86050021
2026-02-09 03:06:42,172 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:06:42,172 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,180 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:42,192 - INFO - Verified VN network id 17 for VN d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,193 - INFO - Verifications in API Server for VN ctest-right_vn-74394428 passed
2026-02-09 03:06:42,193 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,200 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:42,206 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:42,211 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/f22973fd-3c9d-46f8-9e91-ac4b86050021
2026-02-09 03:06:42,221 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15486559259333771551', 'uuid-lslong': '11409545046641702793'}, 'enable': 'true', 'created': '2026-02-09T03:06:41', 'last-modified': '2026-02-09T03:06:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.331641'}
2026-02-09 03:06:42,226 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:06:42,231 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15486559259333771551', 'uuid-lslong': '11409545046641702793'}, 'enable': 'true', 'created': '2026-02-09T03:06:41', 'last-modified': '2026-02-09T03:06:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.343261'}
2026-02-09 03:06:42,236 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:06:42,242 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15486559259333771551', 'uuid-lslong': '11409545046641702793'}, 'enable': 'true', 'created': '2026-02-09T03:06:41', 'last-modified': '2026-02-09T03:06:41', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.353744'}
2026-02-09 03:06:42,248 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:06:42,248 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-74394428 passed
2026-02-09 03:06:42,248 - DEBUG - ====Verifying policy data for ctest-right_vn-74394428 in API_Server ======
2026-02-09 03:06:42,248 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:42,254 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:42,263 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:42,357 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/d6eb4f98-55a0-411f-9e56-d8c501e57789
2026-02-09 03:06:42,366 - DEBUG - =>VN ctest-right_vn-74394428 has no policy to be verified
2026-02-09 03:06:42,366 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:42,366 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:42,366 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:42,376 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428?flat'}
2026-02-09 03:06:42,377 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:42,377 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:42,388 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428?flat'}
2026-02-09 03:06:42,388 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:42,388 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:06:42,400 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428?flat'}
2026-02-09 03:06:42,400 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-65696099:ctest-right_vn-74394428 is found in opserver
2026-02-09 03:06:42,418 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:06:42,429 - DEBUG - VRF ids for VN ctest-right_vn-74394428: {}
2026-02-09 03:06:42,688 - DEBUG - Response for create_network : {'network': {'id': '76c1d4bb-3bc2-46e9-9145-cffaaa0b587d', 'name': 'ctest-trans_left_vn-36309570', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-65696099', 'ctest-trans_left_vn-36309570'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:42,915 - DEBUG - Response for create_subnet : {'subnet': {'id': '81d9c40e-caa3-463c-b920-a28a4c966c54', 'name': '', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'network_id': '76c1d4bb-3bc2-46e9-9145-cffaaa0b587d', 'ip_version': 4, 'cidr': '123.123.213.192/26', 'allocation_pools': [{'start': '123.123.213.194', 'end': '123.123.213.254'}], 'gateway_ip': '123.123.213.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.123.213.194', 'tags': [], 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f'}}
2026-02-09 03:06:42,936 - INFO - Created VN ctest-trans_left_vn-36309570
2026-02-09 03:06:42,950 - DEBUG - VN ctest-trans_left_vn-36309570 UUID is 76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,012 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:43,018 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:43,027 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:43,132 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,143 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,151 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:43,158 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:43,166 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/99a36cb5-7d97-46c4-846c-602203b5c6e3
2026-02-09 03:06:43,172 - DEBUG - Route Targets: ['target:64512:8000008']
2026-02-09 03:06:43,172 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,179 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:43,194 - INFO - Verified VN network id 18 for VN 76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,194 - INFO - Verifications in API Server for VN ctest-trans_left_vn-36309570 passed
2026-02-09 03:06:43,194 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,202 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:43,208 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:43,214 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/99a36cb5-7d97-46c4-846c-602203b5c6e3
2026-02-09 03:06:43,224 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8557354667607213801', 'uuid-lslong': '10468001584394229885'}, 'enable': 'true', 'created': '2026-02-09T03:06:42', 'last-modified': '2026-02-09T03:06:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.359022'}
2026-02-09 03:06:43,229 - DEBUG - Route Targets: ['target:64512:8000008']
2026-02-09 03:06:43,234 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8557354667607213801', 'uuid-lslong': '10468001584394229885'}, 'enable': 'true', 'created': '2026-02-09T03:06:42', 'last-modified': '2026-02-09T03:06:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367488'}
2026-02-09 03:06:43,240 - DEBUG - Route Targets: ['target:64512:8000008']
2026-02-09 03:06:43,244 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8557354667607213801', 'uuid-lslong': '10468001584394229885'}, 'enable': 'true', 'created': '2026-02-09T03:06:42', 'last-modified': '2026-02-09T03:06:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.375469'}
2026-02-09 03:06:43,250 - DEBUG - Route Targets: ['target:64512:8000008']
2026-02-09 03:06:43,250 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-36309570 passed
2026-02-09 03:06:43,250 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-36309570 in API_Server ======
2026-02-09 03:06:43,250 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:43,256 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:43,268 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:43,359 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/76c1d4bb-3bc2-46e9-9145-cffaaa0b587d
2026-02-09 03:06:43,368 - DEBUG - =>VN ctest-trans_left_vn-36309570 has no policy to be verified
2026-02-09 03:06:43,368 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:43,368 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:43,368 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:43,379 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570?flat'}
2026-02-09 03:06:43,379 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:43,379 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:43,393 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570?flat'}
2026-02-09 03:06:43,393 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:43,393 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:06:43,405 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570?flat'}
2026-02-09 03:06:43,405 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-65696099:ctest-trans_left_vn-36309570 is found in opserver
2026-02-09 03:06:43,426 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:06:43,437 - DEBUG - VRF ids for VN ctest-trans_left_vn-36309570: {}
2026-02-09 03:06:43,722 - DEBUG - Response for create_network : {'network': {'id': '39695e79-02ee-40d8-b22c-69d313273a6a', 'name': 'ctest-trans_right_vn-92398392', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-65696099', 'ctest-trans_right_vn-92398392'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:43,981 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f9531ba1-6b7b-482c-8dda-3f8180bb5ae2', 'name': '', 'tenant_id': 'bcdf3a42882c44369bcaf3e6276e268f', 'network_id': '39695e79-02ee-40d8-b22c-69d313273a6a', 'ip_version': 4, 'cidr': '37.45.18.0/26', 'allocation_pools': [{'start': '37.45.18.2', 'end': '37.45.18.62'}], 'gateway_ip': '37.45.18.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.45.18.2', 'tags': [], 'project_id': 'bcdf3a42882c44369bcaf3e6276e268f'}}
2026-02-09 03:06:44,011 - INFO - Created VN ctest-trans_right_vn-92398392
2026-02-09 03:06:44,024 - DEBUG - VN ctest-trans_right_vn-92398392 UUID is 39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,092 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:44,097 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:44,106 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:44,199 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,213 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,221 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:44,229 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:44,234 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/ff44e468-d149-4a04-94a6-1c92bbae33c2
2026-02-09 03:06:44,239 - DEBUG - Route Targets: ['target:64512:8000009']
2026-02-09 03:06:44,239 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,247 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:44,260 - INFO - Verified VN network id 19 for VN 39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,260 - INFO - Verifications in API Server for VN ctest-trans_right_vn-92398392 passed
2026-02-09 03:06:44,260 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,268 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:44,275 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:44,282 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/ff44e468-d149-4a04-94a6-1c92bbae33c2
2026-02-09 03:06:44,294 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4136941606549733592', 'uuid-lslong': '12838752993006795370'}, 'enable': 'true', 'created': '2026-02-09T03:06:43', 'last-modified': '2026-02-09T03:06:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.352991'}
2026-02-09 03:06:44,299 - DEBUG - Route Targets: ['target:64512:8000009']
2026-02-09 03:06:44,303 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4136941606549733592', 'uuid-lslong': '12838752993006795370'}, 'enable': 'true', 'created': '2026-02-09T03:06:43', 'last-modified': '2026-02-09T03:06:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.371159'}
2026-02-09 03:06:44,308 - DEBUG - Route Targets: ['target:64512:8000009']
2026-02-09 03:06:44,313 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-65696099', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4136941606549733592', 'uuid-lslong': '12838752993006795370'}, 'enable': 'true', 'created': '2026-02-09T03:06:43', 'last-modified': '2026-02-09T03:06:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381026'}
2026-02-09 03:06:44,319 - DEBUG - Route Targets: ['target:64512:8000009']
2026-02-09 03:06:44,319 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-92398392 passed
2026-02-09 03:06:44,319 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-92398392 in API_Server ======
2026-02-09 03:06:44,319 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:44,325 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:44,335 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:44,428 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/39695e79-02ee-40d8-b22c-69d313273a6a
2026-02-09 03:06:44,437 - DEBUG - =>VN ctest-trans_right_vn-92398392 has no policy to be verified
2026-02-09 03:06:44,437 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:44,438 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:44,438 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:44,450 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392?flat'}
2026-02-09 03:06:44,450 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:44,450 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:44,463 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392?flat'}
2026-02-09 03:06:44,463 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:44,463 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:06:44,475 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392?flat'}
2026-02-09 03:06:44,475 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-65696099:ctest-trans_right_vn-92398392 is found in opserver
2026-02-09 03:06:44,496 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:06:44,506 - DEBUG - VRF ids for VN ctest-trans_right_vn-92398392: {}
2026-02-09 03:06:45,487 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1)
2026-02-09 03:06:46,755 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2)
2026-02-09 03:06:46,755 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-13798420']
2026-02-09 03:06:47,082 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-13798420'] created succesfully
2026-02-09 03:06:47,435 - DEBUG - Image by name tiny_trans_fw not found
2026-02-09 03:06:47,435 - DEBUG - Installing image tiny_trans_fw
2026-02-09 03:06:47,435 - 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'}
2026-02-09 03:06:47,435 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-02-09 03:06:47,435 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2026-02-09 03:06:47,454 - 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
2026-02-09 03:06:47,479 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-02-09 03:06:47,479 - DEBUG - Unzip image
2026-02-09 03:06:47,479 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2026-02-09 03:06:47,501 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2026-02-09 03:06:47,501 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-13798420']
2026-02-09 03:06:47,594 - INFO - Service template: ['default-domain', 'ctest-service_template_1-13798420'] deleted successfully.
2026-02-09 03:06:47,594 - INFO - Skip interface_detach for VM ctest-right_vm-07114950 in state BUILD
2026-02-09 03:06:47,595 - INFO - Deleting VM ctest-right_vm-07114950
2026-02-09 03:06:47,595 - ERROR - VM ctest-right_vm-07114950 has failed to come up
2026-02-09 03:06:47,595 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:47,595 - ERROR - VM ctest-right_vm-07114950 has failed to come up
2026-02-09 03:06:47,595 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:47,742 - INFO - Skip interface_detach for VM ctest-left_vm-22361739 in state BUILD
2026-02-09 03:06:47,743 - INFO - Deleting VM ctest-left_vm-22361739
2026-02-09 03:06:47,743 - ERROR - VM ctest-left_vm-22361739 has failed to come up
2026-02-09 03:06:47,743 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:47,743 - ERROR - VM ctest-left_vm-22361739 has failed to come up
2026-02-09 03:06:47,743 - ERROR - Nova failed to get host of the VM
2026-02-09 03:06:47,819 - INFO - Deleting VN ctest-trans_right_vn-92398392
2026-02-09 03:06:47,981 - DEBUG - Response for deleting network ()
2026-02-09 03:06:47,981 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c8089eb8-fdd5-4aea-98e9-cd46e652970e
2026-02-09 03:06:47,988 - DEBUG - Response Code: 404
2026-02-09 03:06:47,988 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:48,008 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:48,030 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:48,139 - INFO - Validated that VN ctest-trans_right_vn-92398392 is not found in API Server
2026-02-09 03:06:48,151 - DEBUG - VN ctest-trans_right_vn-92398392 is not present in Agent 10.0.0.27
2026-02-09 03:06:48,208 - DEBUG - VN ctest-trans_right_vn-92398392 is not present in Agent 10.0.0.65
2026-02-09 03:06:48,208 - INFO - Validated that VN ctest-trans_right_vn-92398392 is not in any agent
2026-02-09 03:06:48,252 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-92398392 info
2026-02-09 03:06:48,252 - INFO - Deleting VN ctest-trans_left_vn-36309570
2026-02-09 03:06:48,398 - DEBUG - Response for deleting network ()
2026-02-09 03:06:48,398 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/16f87c94-418f-4cc8-aaf8-f4b8b979d313
2026-02-09 03:06:48,403 - DEBUG - Response Code: 404
2026-02-09 03:06:48,403 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:48,415 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:48,434 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:48,531 - INFO - Validated that VN ctest-trans_left_vn-36309570 is not found in API Server
2026-02-09 03:06:48,542 - DEBUG - VN ctest-trans_left_vn-36309570 is not present in Agent 10.0.0.27
2026-02-09 03:06:48,563 - DEBUG - VN ctest-trans_left_vn-36309570 is not present in Agent 10.0.0.65
2026-02-09 03:06:48,563 - INFO - Validated that VN ctest-trans_left_vn-36309570 is not in any agent
2026-02-09 03:06:48,609 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-36309570 info
2026-02-09 03:06:48,609 - INFO - Deleting VN ctest-right_vn-74394428
2026-02-09 03:06:48,647 - DEBUG - VN d6eb4f98-55a0-411f-9e56-d8c501e57789 still in use: Unable to complete operation on network d6eb4f98-55a0-411f-9e56-d8c501e57789. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-db244fe7-094f-4ff9-8cda-5fbebd5ef824']
2026-02-09 03:06:48,647 - WARNING - Deleting VN ctest-right_vn-74394428 failed..Will retry
2026-02-09 03:06:50,792 - DEBUG - Response for deleting network ()
2026-02-09 03:06:50,792 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/ddd2f16b-2a9b-489e-a746-cdc9384d55f6
2026-02-09 03:06:50,797 - DEBUG - Response Code: 404
2026-02-09 03:06:50,797 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:50,801 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:50,818 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:50,921 - INFO - Validated that VN ctest-right_vn-74394428 is not found in API Server
2026-02-09 03:06:50,932 - DEBUG - VN ctest-right_vn-74394428 is not present in Agent 10.0.0.27
2026-02-09 03:06:50,943 - DEBUG - VN ctest-right_vn-74394428 is not present in Agent 10.0.0.65
2026-02-09 03:06:50,943 - INFO - Validated that VN ctest-right_vn-74394428 is not in any agent
2026-02-09 03:06:50,973 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-74394428 info
2026-02-09 03:06:50,974 - INFO - Deleting VN ctest-left_vn-11149586
2026-02-09 03:06:51,145 - DEBUG - Response for deleting network ()
2026-02-09 03:06:51,146 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/eca29f6c-4c0d-41a7-be09-d6845ee8c4e2
2026-02-09 03:06:51,161 - DEBUG - Response Code: 404
2026-02-09 03:06:51,161 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:51,172 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:51,223 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:51,333 - INFO - Validated that VN ctest-left_vn-11149586 is not found in API Server
2026-02-09 03:06:51,345 - DEBUG - VN ctest-left_vn-11149586 is not present in Agent 10.0.0.27
2026-02-09 03:06:51,357 - DEBUG - VN ctest-left_vn-11149586 is not present in Agent 10.0.0.65
2026-02-09 03:06:51,357 - INFO - Validated that VN ctest-left_vn-11149586 is not in any agent
2026-02-09 03:06:51,394 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-11149586 info
2026-02-09 03:06:51,395 - INFO - Deleting VN ctest-mgmt_vn-94476554
2026-02-09 03:06:51,557 - DEBUG - Response for deleting network ()
2026-02-09 03:06:51,557 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/13f0fb8e-4f51-444f-bd1e-fd74ef220d74
2026-02-09 03:06:51,562 - DEBUG - Response Code: 404
2026-02-09 03:06:51,562 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:51,572 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:51,587 - DEBUG - Requesting: http://10.0.0.19:8082/project/bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:51,689 - INFO - Validated that VN ctest-mgmt_vn-94476554 is not found in API Server
2026-02-09 03:06:51,698 - DEBUG - VN ctest-mgmt_vn-94476554 is not present in Agent 10.0.0.27
2026-02-09 03:06:51,713 - DEBUG - VN ctest-mgmt_vn-94476554 is not present in Agent 10.0.0.65
2026-02-09 03:06:51,713 - INFO - Validated that VN ctest-mgmt_vn-94476554 is not in any agent
2026-02-09 03:06:51,747 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-94476554 info
2026-02-09 03:06:53,044 - DEBUG - No XMPP flaps were noticed during the test
2026-02-09 03:06:53,044 - INFO -
2026-02-09 03:06:53,045 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2026-02-09 03:06:53,045 - INFO - --------------------------------------------------------------------------------
2026-02-09 03:06:53,774 - INFO - Deleted project: ctest-TestSvcRegr-65696099, ID : bcdf3a42-882c-4436-9bca-f3e6276e268f
2026-02-09 03:06:53,959 - INFO - Domain Default found not creating
2026-02-09 03:06:54,105 - INFO - Project ctest-TestSvcRegr-10339280 not found, creating it
2026-02-09 03:06:54,613 - INFO - Created Project:ctest-TestSvcRegr-10339280, ID : 51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:06:56,124 - INFO - ================================================================================
2026-02-09 03:06:56,124 - INFO - STARTING TEST : test_svc_CEM_22032
2026-02-09 03:06:57,408 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.18': '0', '10.20.0.25': '0'}} with
2026-02-09 03:06:57,408 - INFO - Initial checks done. Running the testcase now
2026-02-09 03:06:57,408 - INFO -
2026-02-09 03:06:58,144 - DEBUG - Response for create_network : {'network': {'id': 'e957842b-0d7d-4a22-bfca-9f8485e67a2b', 'name': 'ctest-mgmt_vn-41664918', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'ctest-mgmt_vn-41664918'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:06:58,427 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a464f683-437d-4d2a-99a0-8ef857396055', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': 'e957842b-0d7d-4a22-bfca-9f8485e67a2b', 'ip_version': 4, 'cidr': '207.46.232.128/26', 'allocation_pools': [{'start': '207.46.232.130', 'end': '207.46.232.190'}], 'gateway_ip': '207.46.232.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '207.46.232.130', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:06:58,445 - INFO - Created VN ctest-mgmt_vn-41664918
2026-02-09 03:06:58,456 - DEBUG - VN ctest-mgmt_vn-41664918 UUID is e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:58,522 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:58,812 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:58,840 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:58,854 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:06:58,941 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:58,954 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:58,965 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:06:58,975 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:06:58,983 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/5ba2d776-93de-4b79-a248-d7da153a178f
2026-02-09 03:06:58,989 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:58,989 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:58,999 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:06:59,016 - INFO - Verified VN network id 15 for VN e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:59,016 - INFO - Verifications in API Server for VN ctest-mgmt_vn-41664918 passed
2026-02-09 03:06:59,016 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:59,024 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:06:59,030 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:06:59,036 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/5ba2d776-93de-4b79-a248-d7da153a178f
2026-02-09 03:06:59,046 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16814053054255811106', 'uuid-lslong': '13820033798070762027'}, 'enable': 'true', 'created': '2026-02-09T03:06:58', 'last-modified': '2026-02-09T03:06:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.659903'}
2026-02-09 03:06:59,051 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:59,055 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16814053054255811106', 'uuid-lslong': '13820033798070762027'}, 'enable': 'true', 'created': '2026-02-09T03:06:58', 'last-modified': '2026-02-09T03:06:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.671052'}
2026-02-09 03:06:59,060 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:59,065 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16814053054255811106', 'uuid-lslong': '13820033798070762027'}, 'enable': 'true', 'created': '2026-02-09T03:06:58', 'last-modified': '2026-02-09T03:06:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.681187'}
2026-02-09 03:06:59,071 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:06:59,071 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-41664918 passed
2026-02-09 03:06:59,071 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-41664918 in API_Server ======
2026-02-09 03:06:59,071 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:06:59,078 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:06:59,089 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:06:59,177 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/e957842b-0d7d-4a22-bfca-9f8485e67a2b
2026-02-09 03:06:59,185 - DEBUG - =>VN ctest-mgmt_vn-41664918 has no policy to be verified
2026-02-09 03:06:59,185 - DEBUG - Verifying the vn in opserver
2026-02-09 03:06:59,185 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918 virtual network link through opserver 10.0.0.19
2026-02-09 03:06:59,186 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:59,475 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:06:59,530 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918?flat'}
2026-02-09 03:06:59,530 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918 virtual network link through opserver 10.0.0.33
2026-02-09 03:06:59,530 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:59,819 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:06:59,875 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918?flat'}
2026-02-09 03:06:59,875 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918 virtual network link through opserver 10.0.0.41
2026-02-09 03:06:59,875 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:00,170 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:00,227 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918?flat'}
2026-02-09 03:07:00,227 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-41664918 is found in opserver
2026-02-09 03:07:00,247 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:00,257 - DEBUG - VRF ids for VN ctest-mgmt_vn-41664918: {}
2026-02-09 03:07:00,561 - DEBUG - Response for create_network : {'network': {'id': '2459430c-e0f5-4fdd-a823-d916dcd027b9', 'name': 'service-left-vn-62384627', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'service-left-vn-62384627'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:07:00,816 - DEBUG - Response for create_subnet : {'subnet': {'id': '5baa0e79-8a38-41f0-99cb-bd8bba01f170', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': '2459430c-e0f5-4fdd-a823-d916dcd027b9', 'ip_version': 4, 'cidr': '182.71.17.0/26', 'allocation_pools': [{'start': '182.71.17.2', 'end': '182.71.17.62'}], 'gateway_ip': '182.71.17.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.71.17.2', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:07:00,847 - INFO - Created VN service-left-vn-62384627
2026-02-09 03:07:00,862 - DEBUG - VN service-left-vn-62384627 UUID is 2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:00,934 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:00,940 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:00,950 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:01,038 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,050 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,058 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:01,065 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:01,071 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/5bf39960-f96c-4c56-89e0-607255418b84
2026-02-09 03:07:01,077 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:01,078 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,086 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:01,100 - INFO - Verified VN network id 16 for VN 2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,100 - INFO - Verifications in API Server for VN service-left-vn-62384627 passed
2026-02-09 03:07:01,100 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,110 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:01,118 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:01,124 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/5bf39960-f96c-4c56-89e0-607255418b84
2026-02-09 03:07:01,135 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2619198380885495773', 'uuid-lslong': '12115766114773903289'}, 'enable': 'true', 'created': '2026-02-09T03:07:00', 'last-modified': '2026-02-09T03:07:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.370760'}
2026-02-09 03:07:01,140 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:01,145 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2619198380885495773', 'uuid-lslong': '12115766114773903289'}, 'enable': 'true', 'created': '2026-02-09T03:07:00', 'last-modified': '2026-02-09T03:07:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.384329'}
2026-02-09 03:07:01,153 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:01,158 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2619198380885495773', 'uuid-lslong': '12115766114773903289'}, 'enable': 'true', 'created': '2026-02-09T03:07:00', 'last-modified': '2026-02-09T03:07:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397803'}
2026-02-09 03:07:01,170 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:01,170 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-62384627 passed
2026-02-09 03:07:01,171 - DEBUG - ====Verifying policy data for service-left-vn-62384627 in API_Server ======
2026-02-09 03:07:01,171 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:01,177 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:01,186 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:01,300 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/2459430c-e0f5-4fdd-a823-d916dcd027b9
2026-02-09 03:07:01,310 - DEBUG - =>VN service-left-vn-62384627 has no policy to be verified
2026-02-09 03:07:01,310 - DEBUG - Verifying the vn in opserver
2026-02-09 03:07:01,310 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627 virtual network link through opserver 10.0.0.19
2026-02-09 03:07:01,310 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:07:01,397 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627?flat'}
2026-02-09 03:07:01,397 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627 virtual network link through opserver 10.0.0.33
2026-02-09 03:07:01,397 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:07:01,470 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627?flat'}
2026-02-09 03:07:01,470 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627 virtual network link through opserver 10.0.0.41
2026-02-09 03:07:01,470 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:01,529 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627?flat'}
2026-02-09 03:07:01,529 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:service-left-vn-62384627 is found in opserver
2026-02-09 03:07:01,557 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:01,567 - DEBUG - VRF ids for VN service-left-vn-62384627: {}
2026-02-09 03:07:01,875 - DEBUG - Response for create_network : {'network': {'id': '002ef90c-5e7c-4102-9956-80bc30606241', 'name': 'service-right-vn-96936862', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'service-right-vn-96936862'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:07:02,155 - DEBUG - Response for create_subnet : {'subnet': {'id': '55aa4aeb-e9fd-4150-be9f-b0778ece6e2a', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': '002ef90c-5e7c-4102-9956-80bc30606241', 'ip_version': 4, 'cidr': '114.159.151.128/26', 'allocation_pools': [{'start': '114.159.151.130', 'end': '114.159.151.190'}], 'gateway_ip': '114.159.151.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.159.151.130', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:07:02,176 - INFO - Created VN service-right-vn-96936862
2026-02-09 03:07:02,187 - DEBUG - VN service-right-vn-96936862 UUID is 002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,265 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:02,271 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:02,281 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:02,373 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,389 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,398 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:02,406 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:02,416 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/aaf812e1-5365-49f8-90f3-aad0c74b5b37
2026-02-09 03:07:02,423 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:02,423 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,431 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:02,446 - INFO - Verified VN network id 17 for VN 002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,446 - INFO - Verifications in API Server for VN service-right-vn-96936862 passed
2026-02-09 03:07:02,446 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,457 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:02,464 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:02,473 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/aaf812e1-5365-49f8-90f3-aad0c74b5b37
2026-02-09 03:07:02,484 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13221680448815362', 'uuid-lslong': '11049160281553920577'}, 'enable': 'true', 'created': '2026-02-09T03:07:01', 'last-modified': '2026-02-09T03:07:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387449'}
2026-02-09 03:07:02,489 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:02,494 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13221680448815362', 'uuid-lslong': '11049160281553920577'}, 'enable': 'true', 'created': '2026-02-09T03:07:01', 'last-modified': '2026-02-09T03:07:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.394141'}
2026-02-09 03:07:02,500 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:02,507 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13221680448815362', 'uuid-lslong': '11049160281553920577'}, 'enable': 'true', 'created': '2026-02-09T03:07:01', 'last-modified': '2026-02-09T03:07:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405926'}
2026-02-09 03:07:02,517 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:02,517 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-96936862 passed
2026-02-09 03:07:02,517 - DEBUG - ====Verifying policy data for service-right-vn-96936862 in API_Server ======
2026-02-09 03:07:02,517 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:02,523 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:02,535 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:02,635 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/002ef90c-5e7c-4102-9956-80bc30606241
2026-02-09 03:07:02,644 - DEBUG - =>VN service-right-vn-96936862 has no policy to be verified
2026-02-09 03:07:02,644 - DEBUG - Verifying the vn in opserver
2026-02-09 03:07:02,644 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862 virtual network link through opserver 10.0.0.19
2026-02-09 03:07:02,644 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:07:02,709 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862?flat'}
2026-02-09 03:07:02,709 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862 virtual network link through opserver 10.0.0.33
2026-02-09 03:07:02,709 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:07:02,772 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862?flat'}
2026-02-09 03:07:02,772 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862 virtual network link through opserver 10.0.0.41
2026-02-09 03:07:02,772 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:02,842 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862?flat'}
2026-02-09 03:07:02,842 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:service-right-vn-96936862 is found in opserver
2026-02-09 03:07:02,862 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:02,872 - DEBUG - VRF ids for VN service-right-vn-96936862: {}
2026-02-09 03:07:02,925 - DEBUG - Services list from nova: [, , , ]
2026-02-09 03:07:03,933 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1)
2026-02-09 03:07:05,179 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2)
2026-02-09 03:07:05,179 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-15977792']
2026-02-09 03:07:05,476 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-15977792'] created succesfully
2026-02-09 03:07:05,820 - DEBUG - Image by name tiny_nat_fw not found
2026-02-09 03:07:05,820 - DEBUG - Installing image tiny_nat_fw
2026-02-09 03:07:05,820 - 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'"}
2026-02-09 03:07:05,820 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:05,820 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:05,820 - DEBUG - Unzip image
2026-02-09 03:07:05,820 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:05,841 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-02-09 03:07:05,842 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-15977792']
2026-02-09 03:07:05,946 - INFO - Service template: ['default-domain', 'ctest-service_template_1-15977792'] deleted successfully.
2026-02-09 03:07:05,947 - INFO - Skip interface_detach for VM ctest-right_vm-86041744 in state BUILD
2026-02-09 03:07:05,947 - INFO - Deleting VM ctest-right_vm-86041744
2026-02-09 03:07:05,999 - ERROR - VM ctest-right_vm-86041744 has failed to come up
2026-02-09 03:07:05,999 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:05,999 - ERROR - VM ctest-right_vm-86041744 has failed to come up
2026-02-09 03:07:05,999 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:06,066 - INFO - Skip interface_detach for VM ctest-left_vm-53850837 in state BUILD
2026-02-09 03:07:06,066 - INFO - Deleting VM ctest-left_vm-53850837
2026-02-09 03:07:06,066 - ERROR - VM ctest-left_vm-53850837 has failed to come up
2026-02-09 03:07:06,066 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:06,066 - ERROR - VM ctest-left_vm-53850837 has failed to come up
2026-02-09 03:07:06,066 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:06,126 - INFO - Deleting VN service-right-vn-96936862
2026-02-09 03:07:06,165 - DEBUG - VN 002ef90c-5e7c-4102-9956-80bc30606241 still in use: Unable to complete operation on network 002ef90c-5e7c-4102-9956-80bc30606241. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e04ec9ce-b6c9-4356-bbb3-2acf4d621e40']
2026-02-09 03:07:06,165 - WARNING - Deleting VN service-right-vn-96936862 failed..Will retry
2026-02-09 03:07:08,316 - DEBUG - Response for deleting network ()
2026-02-09 03:07:08,316 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/35fe74ca-ad21-4ad3-9136-427b26fc986d
2026-02-09 03:07:08,326 - DEBUG - Response Code: 404
2026-02-09 03:07:08,327 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:08,336 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:08,370 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:08,472 - INFO - Validated that VN service-right-vn-96936862 is not found in API Server
2026-02-09 03:07:08,484 - DEBUG - VN service-right-vn-96936862 is not present in Agent 10.0.0.27
2026-02-09 03:07:08,499 - DEBUG - VN service-right-vn-96936862 is not present in Agent 10.0.0.65
2026-02-09 03:07:08,499 - INFO - Validated that VN service-right-vn-96936862 is not in any agent
2026-02-09 03:07:08,533 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-96936862 info
2026-02-09 03:07:08,533 - INFO - Deleting VN service-left-vn-62384627
2026-02-09 03:07:08,687 - DEBUG - Response for deleting network ()
2026-02-09 03:07:08,687 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/7bce2471-b509-45e7-91cc-eb5fe1caeee2
2026-02-09 03:07:08,696 - DEBUG - Response Code: 404
2026-02-09 03:07:08,696 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:08,704 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:08,716 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:08,802 - INFO - Validated that VN service-left-vn-62384627 is not found in API Server
2026-02-09 03:07:08,813 - DEBUG - VN service-left-vn-62384627 is not present in Agent 10.0.0.27
2026-02-09 03:07:08,825 - DEBUG - VN service-left-vn-62384627 is not present in Agent 10.0.0.65
2026-02-09 03:07:08,825 - INFO - Validated that VN service-left-vn-62384627 is not in any agent
2026-02-09 03:07:08,859 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-62384627 info
2026-02-09 03:07:08,859 - INFO - Deleting VN ctest-mgmt_vn-41664918
2026-02-09 03:07:09,015 - DEBUG - Response for deleting network ()
2026-02-09 03:07:09,015 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/4c496b97-a7fe-4af0-bf93-10486bfb7d9d
2026-02-09 03:07:09,026 - DEBUG - Response Code: 404
2026-02-09 03:07:09,027 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:09,035 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:09,047 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:09,135 - INFO - Validated that VN ctest-mgmt_vn-41664918 is not found in API Server
2026-02-09 03:07:09,146 - DEBUG - VN ctest-mgmt_vn-41664918 is not present in Agent 10.0.0.27
2026-02-09 03:07:09,157 - DEBUG - VN ctest-mgmt_vn-41664918 is not present in Agent 10.0.0.65
2026-02-09 03:07:09,157 - INFO - Validated that VN ctest-mgmt_vn-41664918 is not in any agent
2026-02-09 03:07:09,188 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-41664918 info
2026-02-09 03:07:10,450 - DEBUG - No XMPP flaps were noticed during the test
2026-02-09 03:07:10,451 - INFO -
2026-02-09 03:07:10,451 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:14]
2026-02-09 03:07:10,451 - INFO - --------------------------------------------------------------------------------
2026-02-09 03:07:10,456 - INFO - ================================================================================
2026-02-09 03:07:10,456 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-02-09 03:07:11,735 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0', '10.20.0.25': '0'}, '10.0.0.65': {'10.20.0.18': '0', '10.20.0.25': '0'}} with
2026-02-09 03:07:11,735 - INFO - Initial checks done. Running the testcase now
2026-02-09 03:07:11,735 - INFO -
2026-02-09 03:07:11,974 - DEBUG - Response for create_network : {'network': {'id': '9d743bc5-972d-44b9-80ac-e47ab177afd3', 'name': 'ctest-mgmt_vn-09571134', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'ctest-mgmt_vn-09571134'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:07:12,197 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd04b14d-a830-4c64-a249-77fdbf703613', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': '9d743bc5-972d-44b9-80ac-e47ab177afd3', 'ip_version': 4, 'cidr': '111.157.209.128/26', 'allocation_pools': [{'start': '111.157.209.130', 'end': '111.157.209.190'}], 'gateway_ip': '111.157.209.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.157.209.130', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:07:12,218 - INFO - Created VN ctest-mgmt_vn-09571134
2026-02-09 03:07:12,229 - DEBUG - VN ctest-mgmt_vn-09571134 UUID is 9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,297 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:12,302 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:12,314 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:12,405 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,417 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,427 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:12,436 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:12,443 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/d24199fe-8f57-4d5d-bf3c-772a12b75ec4
2026-02-09 03:07:12,449 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:07:12,449 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,457 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:12,472 - INFO - Verified VN network id 15 for VN 9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,472 - INFO - Verifications in API Server for VN ctest-mgmt_vn-09571134 passed
2026-02-09 03:07:12,472 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,481 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:12,487 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:12,494 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/d24199fe-8f57-4d5d-bf3c-772a12b75ec4
2026-02-09 03:07:12,507 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11345759081084044473', 'uuid-lslong': '9272036948463562707'}, 'enable': 'true', 'created': '2026-02-09T03:07:11', 'last-modified': '2026-02-09T03:07:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.368255'}
2026-02-09 03:07:12,521 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:07:12,529 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11345759081084044473', 'uuid-lslong': '9272036948463562707'}, 'enable': 'true', 'created': '2026-02-09T03:07:11', 'last-modified': '2026-02-09T03:07:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392370'}
2026-02-09 03:07:12,536 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:07:12,542 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11345759081084044473', 'uuid-lslong': '9272036948463562707'}, 'enable': 'true', 'created': '2026-02-09T03:07:11', 'last-modified': '2026-02-09T03:07:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.404876'}
2026-02-09 03:07:12,547 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-09 03:07:12,547 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-09571134 passed
2026-02-09 03:07:12,547 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-09571134 in API_Server ======
2026-02-09 03:07:12,547 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:12,553 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:12,564 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:12,656 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/9d743bc5-972d-44b9-80ac-e47ab177afd3
2026-02-09 03:07:12,665 - DEBUG - =>VN ctest-mgmt_vn-09571134 has no policy to be verified
2026-02-09 03:07:12,665 - DEBUG - Verifying the vn in opserver
2026-02-09 03:07:12,665 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134 virtual network link through opserver 10.0.0.19
2026-02-09 03:07:12,665 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:07:12,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134?flat'}
2026-02-09 03:07:12,675 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134 virtual network link through opserver 10.0.0.33
2026-02-09 03:07:12,675 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:07:12,693 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134?flat'}
2026-02-09 03:07:12,694 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134 virtual network link through opserver 10.0.0.41
2026-02-09 03:07:12,694 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:12,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134?flat'}
2026-02-09 03:07:12,707 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:ctest-mgmt_vn-09571134 is found in opserver
2026-02-09 03:07:12,727 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:12,736 - DEBUG - VRF ids for VN ctest-mgmt_vn-09571134: {}
2026-02-09 03:07:12,984 - DEBUG - Response for create_network : {'network': {'id': '359c6e51-9531-4089-8b09-1e90018d2880', 'name': 'ctest-left_vn-82265930', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'ctest-left_vn-82265930'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:07:13,194 - DEBUG - Response for create_subnet : {'subnet': {'id': '8b7a7605-18e8-4b45-bc16-817b85746d38', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': '359c6e51-9531-4089-8b09-1e90018d2880', 'ip_version': 4, 'cidr': '91.63.62.64/26', 'allocation_pools': [{'start': '91.63.62.66', 'end': '91.63.62.126'}], 'gateway_ip': '91.63.62.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.63.62.66', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:07:13,215 - INFO - Created VN ctest-left_vn-82265930
2026-02-09 03:07:13,227 - DEBUG - VN ctest-left_vn-82265930 UUID is 359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,300 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:13,305 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:13,315 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:13,412 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,422 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,430 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:13,438 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:13,445 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/79089a98-f9df-459d-8d2b-54a95ef89dd1
2026-02-09 03:07:13,452 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:13,452 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,461 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:13,476 - INFO - Verified VN network id 16 for VN 359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,476 - INFO - Verifications in API Server for VN ctest-left_vn-82265930 passed
2026-02-09 03:07:13,476 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,487 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:13,494 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:13,501 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/79089a98-f9df-459d-8d2b-54a95ef89dd1
2026-02-09 03:07:13,517 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3863083877051482249', 'uuid-lslong': '10018572449912531072'}, 'enable': 'true', 'created': '2026-02-09T03:07:12', 'last-modified': '2026-02-09T03:07:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369249'}
2026-02-09 03:07:13,522 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:13,528 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3863083877051482249', 'uuid-lslong': '10018572449912531072'}, 'enable': 'true', 'created': '2026-02-09T03:07:12', 'last-modified': '2026-02-09T03:07:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381892'}
2026-02-09 03:07:13,534 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:13,540 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3863083877051482249', 'uuid-lslong': '10018572449912531072'}, 'enable': 'true', 'created': '2026-02-09T03:07:12', 'last-modified': '2026-02-09T03:07:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393489'}
2026-02-09 03:07:13,546 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-09 03:07:13,546 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-82265930 passed
2026-02-09 03:07:13,546 - DEBUG - ====Verifying policy data for ctest-left_vn-82265930 in API_Server ======
2026-02-09 03:07:13,546 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:13,553 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:13,566 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:13,660 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/359c6e51-9531-4089-8b09-1e90018d2880
2026-02-09 03:07:13,670 - DEBUG - =>VN ctest-left_vn-82265930 has no policy to be verified
2026-02-09 03:07:13,670 - DEBUG - Verifying the vn in opserver
2026-02-09 03:07:13,670 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930 virtual network link through opserver 10.0.0.19
2026-02-09 03:07:13,670 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:07:13,682 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930?flat'}
2026-02-09 03:07:13,682 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930 virtual network link through opserver 10.0.0.33
2026-02-09 03:07:13,682 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:07:13,695 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930?flat'}
2026-02-09 03:07:13,695 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930 virtual network link through opserver 10.0.0.41
2026-02-09 03:07:13,695 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:13,707 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930?flat'}
2026-02-09 03:07:13,707 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:ctest-left_vn-82265930 is found in opserver
2026-02-09 03:07:13,726 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:13,735 - DEBUG - VRF ids for VN ctest-left_vn-82265930: {}
2026-02-09 03:07:13,993 - DEBUG - Response for create_network : {'network': {'id': '8aac3af2-957b-483b-81a3-ceb1dc2af975', 'name': 'ctest-right_vn-31195520', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'project_id': '51cefaa78d084155b44e2b5a6d0ac789', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-10339280', 'ctest-right_vn-31195520'], 'port_security_enabled': True, 'description': ''}}
2026-02-09 03:07:14,198 - DEBUG - Response for create_subnet : {'subnet': {'id': '21617f53-e87c-40b2-90cb-daf477d29471', 'name': '', 'tenant_id': '51cefaa78d084155b44e2b5a6d0ac789', 'network_id': '8aac3af2-957b-483b-81a3-ceb1dc2af975', 'ip_version': 4, 'cidr': '23.211.74.192/26', 'allocation_pools': [{'start': '23.211.74.194', 'end': '23.211.74.254'}], 'gateway_ip': '23.211.74.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23.211.74.194', 'tags': [], 'project_id': '51cefaa78d084155b44e2b5a6d0ac789'}}
2026-02-09 03:07:14,221 - INFO - Created VN ctest-right_vn-31195520
2026-02-09 03:07:14,235 - DEBUG - VN ctest-right_vn-31195520 UUID is 8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,298 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:14,304 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:14,314 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:14,399 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,413 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,422 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:14,429 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:14,436 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/03729dc4-7cc9-4c2d-a448-fec2b661a735
2026-02-09 03:07:14,444 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:14,444 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,453 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:14,469 - INFO - Verified VN network id 17 for VN 8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,469 - INFO - Verifications in API Server for VN ctest-right_vn-31195520 passed
2026-02-09 03:07:14,469 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,478 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:14,485 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:14,491 - DEBUG - Requesting: http://10.0.0.19:8082/route-target/03729dc4-7cc9-4c2d-a448-fec2b661a735
2026-02-09 03:07:14,505 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9992426486792669243', 'uuid-lslong': '9341537315394877813'}, 'enable': 'true', 'created': '2026-02-09T03:07:13', 'last-modified': '2026-02-09T03:07:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.372986'}
2026-02-09 03:07:14,510 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:14,515 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9992426486792669243', 'uuid-lslong': '9341537315394877813'}, 'enable': 'true', 'created': '2026-02-09T03:07:13', 'last-modified': '2026-02-09T03:07:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.379702'}
2026-02-09 03:07:14,521 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:14,526 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-10339280', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9992426486792669243', 'uuid-lslong': '9341537315394877813'}, 'enable': 'true', 'created': '2026-02-09T03:07:13', 'last-modified': '2026-02-09T03:07:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395042'}
2026-02-09 03:07:14,533 - DEBUG - Route Targets: ['target:64512:8000007']
2026-02-09 03:07:14,533 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-31195520 passed
2026-02-09 03:07:14,533 - DEBUG - ====Verifying policy data for ctest-right_vn-31195520 in API_Server ======
2026-02-09 03:07:14,533 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:14,539 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:14,550 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:14,646 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-network/8aac3af2-957b-483b-81a3-ceb1dc2af975
2026-02-09 03:07:14,656 - DEBUG - =>VN ctest-right_vn-31195520 has no policy to be verified
2026-02-09 03:07:14,656 - DEBUG - Verifying the vn in opserver
2026-02-09 03:07:14,656 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520 virtual network link through opserver 10.0.0.19
2026-02-09 03:07:14,656 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2026-02-09 03:07:14,670 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520?flat'}
2026-02-09 03:07:14,670 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520 virtual network link through opserver 10.0.0.33
2026-02-09 03:07:14,670 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks
2026-02-09 03:07:14,682 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520?flat'}
2026-02-09 03:07:14,682 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520 virtual network link through opserver 10.0.0.41
2026-02-09 03:07:14,682 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-02-09 03:07:14,695 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520?flat'}
2026-02-09 03:07:14,695 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-10339280:ctest-right_vn-31195520 is found in opserver
2026-02-09 03:07:14,714 - DEBUG - Do not have enough data to verify VN in agent
2026-02-09 03:07:14,722 - DEBUG - VRF ids for VN ctest-right_vn-31195520: {}
2026-02-09 03:07:15,696 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-2)
2026-02-09 03:07:16,950 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5238-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5238-1)
2026-02-09 03:07:16,950 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72258955']
2026-02-09 03:07:17,254 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72258955'] created succesfully
2026-02-09 03:07:17,581 - DEBUG - Image by name tiny_nat_fw not found
2026-02-09 03:07:17,585 - DEBUG - Installing image tiny_nat_fw
2026-02-09 03:07:17,585 - 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'"}
2026-02-09 03:07:17,585 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:17,585 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:17,585 - DEBUG - Unzip image
2026-02-09 03:07:17,585 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2026-02-09 03:07:17,613 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2026-02-09 03:07:17,614 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72258955']
2026-02-09 03:07:17,707 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72258955'] deleted successfully.
2026-02-09 03:07:17,707 - INFO - Skip interface_detach for VM ctest-right_vm-88552060 in state BUILD
2026-02-09 03:07:17,707 - INFO - Deleting VM ctest-right_vm-88552060
2026-02-09 03:07:17,707 - ERROR - VM ctest-right_vm-88552060 has failed to come up
2026-02-09 03:07:17,707 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:17,708 - ERROR - VM ctest-right_vm-88552060 has failed to come up
2026-02-09 03:07:17,708 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:17,822 - INFO - Skip interface_detach for VM ctest-left_vm-97340232 in state BUILD
2026-02-09 03:07:17,823 - INFO - Deleting VM ctest-left_vm-97340232
2026-02-09 03:07:17,823 - ERROR - VM ctest-left_vm-97340232 has failed to come up
2026-02-09 03:07:17,823 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:17,823 - ERROR - VM ctest-left_vm-97340232 has failed to come up
2026-02-09 03:07:17,823 - ERROR - Nova failed to get host of the VM
2026-02-09 03:07:17,887 - INFO - Deleting VN ctest-right_vn-31195520
2026-02-09 03:07:17,936 - DEBUG - VN 8aac3af2-957b-483b-81a3-ceb1dc2af975 still in use: Unable to complete operation on network 8aac3af2-957b-483b-81a3-ceb1dc2af975. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-271d4e11-1d08-422c-9ed5-19d64d7c6fc1']
2026-02-09 03:07:17,937 - WARNING - Deleting VN ctest-right_vn-31195520 failed..Will retry
2026-02-09 03:07:20,122 - DEBUG - Response for deleting network ()
2026-02-09 03:07:20,122 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/70c1d1fa-1e61-490b-9e6a-0403abb26625
2026-02-09 03:07:20,130 - DEBUG - Response Code: 404
2026-02-09 03:07:20,130 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:20,142 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:20,157 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:20,261 - INFO - Validated that VN ctest-right_vn-31195520 is not found in API Server
2026-02-09 03:07:20,271 - DEBUG - VN ctest-right_vn-31195520 is not present in Agent 10.0.0.27
2026-02-09 03:07:20,280 - DEBUG - VN ctest-right_vn-31195520 is not present in Agent 10.0.0.65
2026-02-09 03:07:20,280 - INFO - Validated that VN ctest-right_vn-31195520 is not in any agent
2026-02-09 03:07:20,308 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-31195520 info
2026-02-09 03:07:20,309 - INFO - Deleting VN ctest-left_vn-82265930
2026-02-09 03:07:20,447 - DEBUG - Response for deleting network ()
2026-02-09 03:07:20,447 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/c95189e7-b9b2-4a55-bf3a-0289e35e379d
2026-02-09 03:07:20,452 - DEBUG - Response Code: 404
2026-02-09 03:07:20,452 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:20,461 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:20,474 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:20,564 - INFO - Validated that VN ctest-left_vn-82265930 is not found in API Server
2026-02-09 03:07:20,575 - DEBUG - VN ctest-left_vn-82265930 is not present in Agent 10.0.0.27
2026-02-09 03:07:20,584 - DEBUG - VN ctest-left_vn-82265930 is not present in Agent 10.0.0.65
2026-02-09 03:07:20,584 - INFO - Validated that VN ctest-left_vn-82265930 is not in any agent
2026-02-09 03:07:20,613 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-82265930 info
2026-02-09 03:07:20,613 - INFO - Deleting VN ctest-mgmt_vn-09571134
2026-02-09 03:07:20,758 - DEBUG - Response for deleting network ()
2026-02-09 03:07:20,759 - DEBUG - Requesting: http://10.0.0.19:8082/routing-instance/84f4392a-f10b-4063-a0af-c3e3ef3c0f3b
2026-02-09 03:07:20,764 - DEBUG - Response Code: 404
2026-02-09 03:07:20,764 - DEBUG - Requesting: http://10.0.0.19:8082/domains
2026-02-09 03:07:20,776 - DEBUG - Requesting: http://10.0.0.19:8082/domain/069df225-a7cb-4796-a8d5-681a22d1bf82
2026-02-09 03:07:20,832 - DEBUG - Requesting: http://10.0.0.19:8082/project/51cefaa7-8d08-4155-b44e-2b5a6d0ac789
2026-02-09 03:07:20,929 - INFO - Validated that VN ctest-mgmt_vn-09571134 is not found in API Server
2026-02-09 03:07:20,940 - DEBUG - VN ctest-mgmt_vn-09571134 is not present in Agent 10.0.0.27
2026-02-09 03:07:20,951 - DEBUG - VN ctest-mgmt_vn-09571134 is not present in Agent 10.0.0.65
2026-02-09 03:07:20,951 - INFO - Validated that VN ctest-mgmt_vn-09571134 is not in any agent
2026-02-09 03:07:20,983 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-09571134 info
2026-02-09 03:07:22,253 - DEBUG - No XMPP flaps were noticed during the test
2026-02-09 03:07:22,253 - INFO -
2026-02-09 03:07:22,253 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2026-02-09 03:07:22,253 - INFO - --------------------------------------------------------------------------------
2026-02-09 03:07:22,951 - INFO - Deleted project: ctest-TestSvcRegr-10339280, ID : 51cefaa7-8d08-4155-b44e-2b5a6d0ac789