]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:45:45,060 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92906273']
2025-08-06 15:45:45,384 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92906273'] created succesfully
2025-08-06 15:45:45,855 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 15:45:45,855 - DEBUG - Installing image tiny_nat_fw
2025-08-06 15:45:45,856 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-06 15:45:45,857 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-06 15:45:45,857 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-06 15:45:45,880 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:45:46,144 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:45:46,145 - DEBUG - Unzip image
2025-08-06 15:45:46,145 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:45:46,173 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 15:45:46,178 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92906273']
2025-08-06 15:45:46,295 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92906273'] deleted successfully.
2025-08-06 15:45:46,295 - INFO - Skip interface_detach for VM ctest-right_vm-53282049 in state BUILD
2025-08-06 15:45:46,295 - INFO - Deleting VM ctest-right_vm-53282049
2025-08-06 15:45:46,332 - ERROR - VM ctest-right_vm-53282049 has failed to come up
2025-08-06 15:45:46,332 - ERROR - Nova failed to get host of the VM
2025-08-06 15:45:46,332 - ERROR - VM ctest-right_vm-53282049 has failed to come up
2025-08-06 15:45:46,333 - ERROR - Nova failed to get host of the VM
2025-08-06 15:45:46,428 - INFO - Skip interface_detach for VM ctest-left_vm-73507806 in state BUILD
2025-08-06 15:45:46,428 - INFO - Deleting VM ctest-left_vm-73507806
2025-08-06 15:45:46,428 - ERROR - VM ctest-left_vm-73507806 has failed to come up
2025-08-06 15:45:46,428 - ERROR - Nova failed to get host of the VM
2025-08-06 15:45:46,428 - ERROR - VM ctest-left_vm-73507806 has failed to come up
2025-08-06 15:45:46,428 - ERROR - Nova failed to get host of the VM
2025-08-06 15:45:46,500 - INFO - Deleting VN ctest-right_vn-93781297
2025-08-06 15:45:46,552 - DEBUG - VN 134a8690-b0d9-42ee-95c0-54dbe2044d42 still in use: Unable to complete operation on network 134a8690-b0d9-42ee-95c0-54dbe2044d42. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f7bbd8e2-f160-4f6a-b9d9-a9326119a3bf']
2025-08-06 15:45:46,552 - WARNING - Deleting VN ctest-right_vn-93781297 failed..Will retry
2025-08-06 15:45:48,763 - DEBUG - Response for deleting network ()
2025-08-06 15:45:48,763 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3689b7d3-7441-4fb4-8423-29dfac55c8a6
2025-08-06 15:45:48,781 - DEBUG - Response Code: 404
2025-08-06 15:45:48,781 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:48,792 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:48,814 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:48,908 - INFO - Validated that VN ctest-right_vn-93781297 is not found in API Server
2025-08-06 15:45:48,922 - DEBUG - VN ctest-right_vn-93781297 is not present in Agent 10.0.0.49
2025-08-06 15:45:48,937 - DEBUG - VN ctest-right_vn-93781297 is not present in Agent 10.0.0.57
2025-08-06 15:45:48,937 - INFO - Validated that VN ctest-right_vn-93781297 is not in any agent
2025-08-06 15:45:48,976 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-93781297 info
2025-08-06 15:45:48,976 - INFO - Deleting VN ctest-left_vn-57154053
2025-08-06 15:45:49,141 - DEBUG - Response for deleting network ()
2025-08-06 15:45:49,141 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb1901da-cb19-498b-af35-7e64e31d6f89
2025-08-06 15:45:49,154 - DEBUG - Response Code: 404
2025-08-06 15:45:49,154 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:49,160 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:49,185 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:49,288 - INFO - Validated that VN ctest-left_vn-57154053 is not found in API Server
2025-08-06 15:45:49,305 - DEBUG - VN ctest-left_vn-57154053 is not present in Agent 10.0.0.49
2025-08-06 15:45:49,319 - DEBUG - VN ctest-left_vn-57154053 is not present in Agent 10.0.0.57
2025-08-06 15:45:49,319 - INFO - Validated that VN ctest-left_vn-57154053 is not in any agent
2025-08-06 15:45:49,370 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-57154053 info
2025-08-06 15:45:49,370 - INFO - Deleting VN ctest-mgmt_vn-13472826
2025-08-06 15:45:49,545 - DEBUG - Response for deleting network ()
2025-08-06 15:45:49,545 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/139dcff8-7610-40e0-b298-1269851fd0a8
2025-08-06 15:45:49,555 - DEBUG - Response Code: 404
2025-08-06 15:45:49,555 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:49,575 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:49,595 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:49,702 - INFO - Validated that VN ctest-mgmt_vn-13472826 is not found in API Server
2025-08-06 15:45:49,721 - DEBUG - VN ctest-mgmt_vn-13472826 is not present in Agent 10.0.0.49
2025-08-06 15:45:49,735 - DEBUG - VN ctest-mgmt_vn-13472826 is not present in Agent 10.0.0.57
2025-08-06 15:45:49,735 - INFO - Validated that VN ctest-mgmt_vn-13472826 is not in any agent
2025-08-06 15:45:49,775 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-13472826 info
2025-08-06 15:45:49,775 - INFO - Deleting VM ctest-vm-in-third-vn-85781275
2025-08-06 15:45:49,851 - INFO - Deleting VN ctest-third-vn-70000851
2025-08-06 15:45:49,897 - DEBUG - VN 9b6231a6-0a7c-44b9-8c6a-f17f31a88609 still in use: Unable to complete operation on network 9b6231a6-0a7c-44b9-8c6a-f17f31a88609. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7dee2fd1-cef7-4482-84ca-83634030c360']
2025-08-06 15:45:49,897 - WARNING - Deleting VN ctest-third-vn-70000851 failed..Will retry
2025-08-06 15:45:52,054 - DEBUG - Response for deleting network ()
2025-08-06 15:45:53,360 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:45:53,360 - INFO -
2025-08-06 15:45:53,360 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:25]
2025-08-06 15:45:53,360 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:45:53,365 - INFO - ================================================================================
2025-08-06 15:45:53,366 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-06 15:45:54,659 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-06 15:45:54,659 - INFO - Initial checks done. Running the testcase now
2025-08-06 15:45:54,660 - INFO -
2025-08-06 15:45:54,932 - DEBUG - Response for create_network : {'network': {'id': 'aecc01ac-c5b0-4002-a153-a728c199c54b', 'name': 'ctest-mgmt_vn-64801314', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'project_id': 'de7a07434cae41e4b054884a3585a6e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-95317165', 'ctest-mgmt_vn-64801314'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:45:55,135 - DEBUG - Response for create_subnet : {'subnet': {'id': '03275ecd-d8d4-4ce7-a1b8-19c82ae947b9', 'name': '', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'network_id': 'aecc01ac-c5b0-4002-a153-a728c199c54b', 'ip_version': 4, 'cidr': '78.150.85.192/26', 'allocation_pools': [{'start': '78.150.85.194', 'end': '78.150.85.254'}], 'gateway_ip': '78.150.85.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.150.85.194', 'tags': [], 'project_id': 'de7a07434cae41e4b054884a3585a6e3'}}
2025-08-06 15:45:55,157 - INFO - Created VN ctest-mgmt_vn-64801314
2025-08-06 15:45:55,168 - DEBUG - VN ctest-mgmt_vn-64801314 UUID is aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,250 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:55,257 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:55,272 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:55,369 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,381 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,394 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:45:55,407 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:45:55,414 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7cd7d86b-1b01-43d9-8170-faeaf5d3336a
2025-08-06 15:45:55,421 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:45:55,422 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,432 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:45:55,448 - INFO - Verified VN network id 9 for VN aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,448 - INFO - Verifications in API Server for VN ctest-mgmt_vn-64801314 passed
2025-08-06 15:45:55,449 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,462 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:45:55,471 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:45:55,480 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/7cd7d86b-1b01-43d9-8170-faeaf5d3336a
2025-08-06 15:45:55,494 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12595444099411099650', 'uuid-lslong': '11624818856661992779'}, 'enable': 'true', 'created': '2025-08-06T15:45:54', 'last-modified': '2025-08-06T15:45:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414738'}
2025-08-06 15:45:55,501 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:45:55,507 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12595444099411099650', 'uuid-lslong': '11624818856661992779'}, 'enable': 'true', 'created': '2025-08-06T15:45:54', 'last-modified': '2025-08-06T15:45:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437031'}
2025-08-06 15:45:55,513 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:45:55,519 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12595444099411099650', 'uuid-lslong': '11624818856661992779'}, 'enable': 'true', 'created': '2025-08-06T15:45:54', 'last-modified': '2025-08-06T15:45:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.437474'}
2025-08-06 15:45:55,526 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:45:55,526 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-64801314 passed
2025-08-06 15:45:55,527 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-64801314 in API_Server ======
2025-08-06 15:45:55,527 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:55,533 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:55,545 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:55,642 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/aecc01ac-c5b0-4002-a153-a728c199c54b
2025-08-06 15:45:55,656 - DEBUG - =>VN ctest-mgmt_vn-64801314 has no policy to be verified
2025-08-06 15:45:55,656 - DEBUG - Verifying the vn in opserver
2025-08-06 15:45:55,656 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314 virtual network link through opserver 10.0.0.254
2025-08-06 15:45:55,656 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:45:55,669 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314?flat'}
2025-08-06 15:45:55,669 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314 virtual network link through opserver 10.0.0.38
2025-08-06 15:45:55,669 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:45:55,681 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314?flat'}
2025-08-06 15:45:55,681 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314 virtual network link through opserver 10.0.0.48
2025-08-06 15:45:55,682 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:45:55,697 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314?flat'}
2025-08-06 15:45:55,697 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-95317165:ctest-mgmt_vn-64801314 is found in opserver
2025-08-06 15:45:55,725 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:45:55,739 - DEBUG - VRF ids for VN ctest-mgmt_vn-64801314: {}
2025-08-06 15:45:56,016 - DEBUG - Response for create_network : {'network': {'id': 'c996d56f-a79c-474b-8404-a22ac470947b', 'name': 'ctest-left_vn-39302189', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'project_id': 'de7a07434cae41e4b054884a3585a6e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-95317165', 'ctest-left_vn-39302189'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:45:56,262 - DEBUG - Response for create_subnet : {'subnet': {'id': '02773da6-7986-4eca-abdf-b115a9018dac', 'name': '', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'network_id': 'c996d56f-a79c-474b-8404-a22ac470947b', 'ip_version': 4, 'cidr': '190.106.84.128/26', 'allocation_pools': [{'start': '190.106.84.130', 'end': '190.106.84.190'}], 'gateway_ip': '190.106.84.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '190.106.84.130', 'tags': [], 'project_id': 'de7a07434cae41e4b054884a3585a6e3'}}
2025-08-06 15:45:56,282 - INFO - Created VN ctest-left_vn-39302189
2025-08-06 15:45:56,293 - DEBUG - VN ctest-left_vn-39302189 UUID is c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,362 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:56,368 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:56,380 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:56,489 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,506 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,520 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:45:56,532 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:45:56,544 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/70e3c7a8-43c9-4701-8653-e4cfc8b8e1f5
2025-08-06 15:45:56,553 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:45:56,553 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,571 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:45:56,593 - INFO - Verified VN network id 10 for VN c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,593 - INFO - Verifications in API Server for VN ctest-left_vn-39302189 passed
2025-08-06 15:45:56,593 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,603 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:45:56,612 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:45:56,620 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/70e3c7a8-43c9-4701-8653-e4cfc8b8e1f5
2025-08-06 15:45:56,634 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14526032323660236619', 'uuid-lslong': '9512906617481368699'}, 'enable': 'true', 'created': '2025-08-06T15:45:55', 'last-modified': '2025-08-06T15:45:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438038'}
2025-08-06 15:45:56,644 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:45:56,651 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14526032323660236619', 'uuid-lslong': '9512906617481368699'}, 'enable': 'true', 'created': '2025-08-06T15:45:55', 'last-modified': '2025-08-06T15:45:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.454887'}
2025-08-06 15:45:56,657 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:45:56,663 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14526032323660236619', 'uuid-lslong': '9512906617481368699'}, 'enable': 'true', 'created': '2025-08-06T15:45:55', 'last-modified': '2025-08-06T15:45:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470395'}
2025-08-06 15:45:56,671 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:45:56,671 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-39302189 passed
2025-08-06 15:45:56,672 - DEBUG - ====Verifying policy data for ctest-left_vn-39302189 in API_Server ======
2025-08-06 15:45:56,672 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:56,679 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:56,695 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:56,788 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/c996d56f-a79c-474b-8404-a22ac470947b
2025-08-06 15:45:56,799 - DEBUG - =>VN ctest-left_vn-39302189 has no policy to be verified
2025-08-06 15:45:56,799 - DEBUG - Verifying the vn in opserver
2025-08-06 15:45:56,799 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189 virtual network link through opserver 10.0.0.254
2025-08-06 15:45:56,800 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:45:56,815 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189?flat'}
2025-08-06 15:45:56,815 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189 virtual network link through opserver 10.0.0.38
2025-08-06 15:45:56,815 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:45:56,829 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189?flat'}
2025-08-06 15:45:56,829 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189 virtual network link through opserver 10.0.0.48
2025-08-06 15:45:56,829 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:45:56,844 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189?flat'}
2025-08-06 15:45:56,845 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-95317165:ctest-left_vn-39302189 is found in opserver
2025-08-06 15:45:56,876 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:45:56,892 - DEBUG - VRF ids for VN ctest-left_vn-39302189: {}
2025-08-06 15:45:57,175 - DEBUG - Response for create_network : {'network': {'id': '0dcaf0d5-b905-45bd-b593-31dbce72207b', 'name': 'ctest-right_vn-30354438', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'project_id': 'de7a07434cae41e4b054884a3585a6e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-95317165', 'ctest-right_vn-30354438'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:45:57,376 - DEBUG - Response for create_subnet : {'subnet': {'id': '2f9f6a68-665e-4c7f-9007-d8b870c5c95c', 'name': '', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'network_id': '0dcaf0d5-b905-45bd-b593-31dbce72207b', 'ip_version': 4, 'cidr': '205.124.111.0/26', 'allocation_pools': [{'start': '205.124.111.2', 'end': '205.124.111.62'}], 'gateway_ip': '205.124.111.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '205.124.111.2', 'tags': [], 'project_id': 'de7a07434cae41e4b054884a3585a6e3'}}
2025-08-06 15:45:57,398 - INFO - Created VN ctest-right_vn-30354438
2025-08-06 15:45:57,413 - DEBUG - VN ctest-right_vn-30354438 UUID is 0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,489 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:57,496 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:57,511 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:57,607 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,622 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,632 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:45:57,641 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:45:57,650 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/983487c1-ced3-4076-8adc-84116eeb4cf4
2025-08-06 15:45:57,658 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:45:57,658 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,669 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:45:57,687 - INFO - Verified VN network id 11 for VN 0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,688 - INFO - Verifications in API Server for VN ctest-right_vn-30354438 passed
2025-08-06 15:45:57,688 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,698 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:45:57,708 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:45:57,717 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/983487c1-ced3-4076-8adc-84116eeb4cf4
2025-08-06 15:45:57,731 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '993871468511446461', 'uuid-lslong': '13083856162572607611'}, 'enable': 'true', 'created': '2025-08-06T15:45:57', 'last-modified': '2025-08-06T15:45:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414635'}
2025-08-06 15:45:57,738 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:45:57,743 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '993871468511446461', 'uuid-lslong': '13083856162572607611'}, 'enable': 'true', 'created': '2025-08-06T15:45:57', 'last-modified': '2025-08-06T15:45:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419367'}
2025-08-06 15:45:57,750 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:45:57,765 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '993871468511446461', 'uuid-lslong': '13083856162572607611'}, 'enable': 'true', 'created': '2025-08-06T15:45:57', 'last-modified': '2025-08-06T15:45:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438722'}
2025-08-06 15:45:57,775 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:45:57,776 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-30354438 passed
2025-08-06 15:45:57,776 - DEBUG - ====Verifying policy data for ctest-right_vn-30354438 in API_Server ======
2025-08-06 15:45:57,776 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:57,783 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:57,798 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:57,904 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/0dcaf0d5-b905-45bd-b593-31dbce72207b
2025-08-06 15:45:57,920 - DEBUG - =>VN ctest-right_vn-30354438 has no policy to be verified
2025-08-06 15:45:57,920 - DEBUG - Verifying the vn in opserver
2025-08-06 15:45:57,920 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438 virtual network link through opserver 10.0.0.254
2025-08-06 15:45:57,920 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:45:57,938 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438?flat'}
2025-08-06 15:45:57,938 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438 virtual network link through opserver 10.0.0.38
2025-08-06 15:45:57,938 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:45:57,952 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438?flat'}
2025-08-06 15:45:57,952 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438 virtual network link through opserver 10.0.0.48
2025-08-06 15:45:57,952 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:45:57,967 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438?flat'}
2025-08-06 15:45:57,967 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-95317165:ctest-right_vn-30354438 is found in opserver
2025-08-06 15:45:57,998 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:45:58,014 - DEBUG - VRF ids for VN ctest-right_vn-30354438: {}
2025-08-06 15:45:58,265 - DEBUG - Response for create_network : {'network': {'id': '6ef5e7ce-f252-4389-aff8-db76ddf2ebe2', 'name': 'ctest-trans_left_vn-70442061', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'project_id': 'de7a07434cae41e4b054884a3585a6e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-95317165', 'ctest-trans_left_vn-70442061'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:45:58,480 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cdd0d066-7c23-4605-b746-edd31837b865', 'name': '', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'network_id': '6ef5e7ce-f252-4389-aff8-db76ddf2ebe2', 'ip_version': 4, 'cidr': '32.177.82.128/26', 'allocation_pools': [{'start': '32.177.82.130', 'end': '32.177.82.190'}], 'gateway_ip': '32.177.82.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '32.177.82.130', 'tags': [], 'project_id': 'de7a07434cae41e4b054884a3585a6e3'}}
2025-08-06 15:45:58,500 - INFO - Created VN ctest-trans_left_vn-70442061
2025-08-06 15:45:58,516 - DEBUG - VN ctest-trans_left_vn-70442061 UUID is 6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,589 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:58,595 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:58,607 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:58,713 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,728 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,739 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:45:58,750 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:45:58,758 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/b6999548-3ecf-4819-b2e6-5657d42ef921
2025-08-06 15:45:58,766 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:45:58,766 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,777 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:45:58,795 - INFO - Verified VN network id 12 for VN 6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,795 - INFO - Verifications in API Server for VN ctest-trans_left_vn-70442061 passed
2025-08-06 15:45:58,795 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:58,808 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:45:58,818 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:45:58,828 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/b6999548-3ecf-4819-b2e6-5657d42ef921
2025-08-06 15:45:58,844 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7995551589480940425', 'uuid-lslong': '12680126054437940194'}, 'enable': 'true', 'created': '2025-08-06T15:45:58', 'last-modified': '2025-08-06T15:45:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395113'}
2025-08-06 15:45:58,850 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:45:58,857 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7995551589480940425', 'uuid-lslong': '12680126054437940194'}, 'enable': 'true', 'created': '2025-08-06T15:45:58', 'last-modified': '2025-08-06T15:45:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413782'}
2025-08-06 15:45:58,864 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:45:58,871 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7995551589480940425', 'uuid-lslong': '12680126054437940194'}, 'enable': 'true', 'created': '2025-08-06T15:45:58', 'last-modified': '2025-08-06T15:45:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420812'}
2025-08-06 15:45:58,879 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-06 15:45:58,879 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-70442061 passed
2025-08-06 15:45:58,879 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-70442061 in API_Server ======
2025-08-06 15:45:58,880 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:58,887 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:58,902 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:58,996 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/6ef5e7ce-f252-4389-aff8-db76ddf2ebe2
2025-08-06 15:45:59,005 - DEBUG - =>VN ctest-trans_left_vn-70442061 has no policy to be verified
2025-08-06 15:45:59,006 - DEBUG - Verifying the vn in opserver
2025-08-06 15:45:59,006 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061 virtual network link through opserver 10.0.0.254
2025-08-06 15:45:59,006 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:45:59,018 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061?flat'}
2025-08-06 15:45:59,018 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061 virtual network link through opserver 10.0.0.38
2025-08-06 15:45:59,019 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:45:59,032 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061?flat'}
2025-08-06 15:45:59,032 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061 virtual network link through opserver 10.0.0.48
2025-08-06 15:45:59,032 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:45:59,045 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061?flat'}
2025-08-06 15:45:59,045 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-95317165:ctest-trans_left_vn-70442061 is found in opserver
2025-08-06 15:45:59,071 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:45:59,083 - DEBUG - VRF ids for VN ctest-trans_left_vn-70442061: {}
2025-08-06 15:45:59,383 - DEBUG - Response for create_network : {'network': {'id': 'f3be7a24-d15f-4912-b9c6-eaec5182b134', 'name': 'ctest-trans_right_vn-36272479', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'project_id': 'de7a07434cae41e4b054884a3585a6e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-95317165', 'ctest-trans_right_vn-36272479'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:45:59,630 - DEBUG - Response for create_subnet : {'subnet': {'id': 'adc4c320-67ea-41d0-9618-20e311f915c8', 'name': '', 'tenant_id': 'de7a07434cae41e4b054884a3585a6e3', 'network_id': 'f3be7a24-d15f-4912-b9c6-eaec5182b134', 'ip_version': 4, 'cidr': '145.71.150.64/26', 'allocation_pools': [{'start': '145.71.150.66', 'end': '145.71.150.126'}], 'gateway_ip': '145.71.150.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '145.71.150.66', 'tags': [], 'project_id': 'de7a07434cae41e4b054884a3585a6e3'}}
2025-08-06 15:45:59,655 - INFO - Created VN ctest-trans_right_vn-36272479
2025-08-06 15:45:59,669 - DEBUG - VN ctest-trans_right_vn-36272479 UUID is f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,742 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:45:59,750 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:45:59,766 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:45:59,862 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,876 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,886 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:45:59,894 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:45:59,901 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/68283777-b893-4951-8ff2-3c3f7f9f9757
2025-08-06 15:45:59,910 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:45:59,910 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,922 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:45:59,940 - INFO - Verified VN network id 13 for VN f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,940 - INFO - Verifications in API Server for VN ctest-trans_right_vn-36272479 passed
2025-08-06 15:45:59,940 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:45:59,955 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:45:59,970 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:45:59,979 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/68283777-b893-4951-8ff2-3c3f7f9f9757
2025-08-06 15:45:59,998 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17563609895341607186', 'uuid-lslong': '13386645243106079028'}, 'enable': 'true', 'created': '2025-08-06T15:45:59', 'last-modified': '2025-08-06T15:45:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.442175'}
2025-08-06 15:46:00,006 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:46:00,012 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17563609895341607186', 'uuid-lslong': '13386645243106079028'}, 'enable': 'true', 'created': '2025-08-06T15:45:59', 'last-modified': '2025-08-06T15:45:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456030'}
2025-08-06 15:46:00,019 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:46:00,027 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-95317165', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17563609895341607186', 'uuid-lslong': '13386645243106079028'}, 'enable': 'true', 'created': '2025-08-06T15:45:59', 'last-modified': '2025-08-06T15:45:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471301'}
2025-08-06 15:46:00,035 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-06 15:46:00,035 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-36272479 passed
2025-08-06 15:46:00,035 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-36272479 in API_Server ======
2025-08-06 15:46:00,035 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:00,042 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:00,055 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:00,148 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/f3be7a24-d15f-4912-b9c6-eaec5182b134
2025-08-06 15:46:00,159 - DEBUG - =>VN ctest-trans_right_vn-36272479 has no policy to be verified
2025-08-06 15:46:00,159 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:00,159 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:00,159 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:00,175 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479?flat'}
2025-08-06 15:46:00,175 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:00,175 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:00,188 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479?flat'}
2025-08-06 15:46:00,188 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:00,188 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:00,202 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479?flat'}
2025-08-06 15:46:00,202 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-95317165:ctest-trans_right_vn-36272479 is found in opserver
2025-08-06 15:46:00,227 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:00,243 - DEBUG - VRF ids for VN ctest-trans_right_vn-36272479: {}
2025-08-06 15:46:01,405 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:46:02,801 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2)
2025-08-06 15:46:02,801 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-13341760']
2025-08-06 15:46:03,109 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-13341760'] created succesfully
2025-08-06 15:46:03,493 - DEBUG - Image by name tiny_trans_fw not found
2025-08-06 15:46:03,493 - DEBUG - Installing image tiny_trans_fw
2025-08-06 15:46:03,493 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-06 15:46:03,493 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:46:03,493 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:46:03,518 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:46:03,556 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:46:03,557 - DEBUG - Unzip image
2025-08-06 15:46:03,557 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-06 15:46:03,574 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-06 15:46:03,575 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-13341760']
2025-08-06 15:46:03,666 - INFO - Service template: ['default-domain', 'ctest-service_template_1-13341760'] deleted successfully.
2025-08-06 15:46:03,666 - INFO - Skip interface_detach for VM ctest-right_vm-60102025 in state BUILD
2025-08-06 15:46:03,667 - INFO - Deleting VM ctest-right_vm-60102025
2025-08-06 15:46:03,667 - ERROR - VM ctest-right_vm-60102025 has failed to come up
2025-08-06 15:46:03,667 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:03,667 - ERROR - VM ctest-right_vm-60102025 has failed to come up
2025-08-06 15:46:03,667 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:03,777 - INFO - Skip interface_detach for VM ctest-left_vm-92767766 in state BUILD
2025-08-06 15:46:03,778 - INFO - Deleting VM ctest-left_vm-92767766
2025-08-06 15:46:03,778 - ERROR - VM ctest-left_vm-92767766 has failed to come up
2025-08-06 15:46:03,778 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:03,778 - ERROR - VM ctest-left_vm-92767766 has failed to come up
2025-08-06 15:46:03,778 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:03,852 - INFO - Deleting VN ctest-trans_right_vn-36272479
2025-08-06 15:46:04,005 - DEBUG - Response for deleting network ()
2025-08-06 15:46:04,005 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/f479e5ae-6ded-4360-a60c-0023bc14e15d
2025-08-06 15:46:04,011 - DEBUG - Response Code: 404
2025-08-06 15:46:04,012 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:04,023 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:04,040 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:04,128 - INFO - Validated that VN ctest-trans_right_vn-36272479 is not found in API Server
2025-08-06 15:46:04,141 - DEBUG - VN ctest-trans_right_vn-36272479 is not present in Agent 10.0.0.49
2025-08-06 15:46:04,179 - DEBUG - VN ctest-trans_right_vn-36272479 is not present in Agent 10.0.0.57
2025-08-06 15:46:04,179 - INFO - Validated that VN ctest-trans_right_vn-36272479 is not in any agent
2025-08-06 15:46:04,220 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-36272479 info
2025-08-06 15:46:04,220 - INFO - Deleting VN ctest-trans_left_vn-70442061
2025-08-06 15:46:04,352 - DEBUG - Response for deleting network ()
2025-08-06 15:46:04,353 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7c53cd20-0411-4af1-87a0-e8bb251caee4
2025-08-06 15:46:04,359 - DEBUG - Response Code: 404
2025-08-06 15:46:04,359 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:04,375 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:04,392 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:04,495 - INFO - Validated that VN ctest-trans_left_vn-70442061 is not found in API Server
2025-08-06 15:46:04,514 - DEBUG - VN ctest-trans_left_vn-70442061 is not present in Agent 10.0.0.49
2025-08-06 15:46:04,552 - DEBUG - VN ctest-trans_left_vn-70442061 is not present in Agent 10.0.0.57
2025-08-06 15:46:04,552 - INFO - Validated that VN ctest-trans_left_vn-70442061 is not in any agent
2025-08-06 15:46:04,605 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-70442061 info
2025-08-06 15:46:04,605 - INFO - Deleting VN ctest-right_vn-30354438
2025-08-06 15:46:04,662 - DEBUG - VN 0dcaf0d5-b905-45bd-b593-31dbce72207b still in use: Unable to complete operation on network 0dcaf0d5-b905-45bd-b593-31dbce72207b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-23b1552d-c0d7-4c25-924e-d7bdb26f6ac6']
2025-08-06 15:46:04,662 - WARNING - Deleting VN ctest-right_vn-30354438 failed..Will retry
2025-08-06 15:46:06,844 - DEBUG - Response for deleting network ()
2025-08-06 15:46:06,844 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/85e62b00-1eda-48eb-b6f1-7a06662411cb
2025-08-06 15:46:06,861 - DEBUG - Response Code: 404
2025-08-06 15:46:06,862 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:06,872 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:06,889 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:06,994 - INFO - Validated that VN ctest-right_vn-30354438 is not found in API Server
2025-08-06 15:46:07,008 - DEBUG - VN ctest-right_vn-30354438 is not present in Agent 10.0.0.49
2025-08-06 15:46:07,023 - DEBUG - VN ctest-right_vn-30354438 is not present in Agent 10.0.0.57
2025-08-06 15:46:07,023 - INFO - Validated that VN ctest-right_vn-30354438 is not in any agent
2025-08-06 15:46:07,060 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-30354438 info
2025-08-06 15:46:07,060 - INFO - Deleting VN ctest-left_vn-39302189
2025-08-06 15:46:07,244 - DEBUG - Response for deleting network ()
2025-08-06 15:46:07,244 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b13115e8-17cc-4ee0-a9bc-70a49b49a0ff
2025-08-06 15:46:07,262 - DEBUG - Response Code: 404
2025-08-06 15:46:07,262 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:07,268 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:07,282 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:07,382 - INFO - Validated that VN ctest-left_vn-39302189 is not found in API Server
2025-08-06 15:46:07,394 - DEBUG - VN ctest-left_vn-39302189 is not present in Agent 10.0.0.49
2025-08-06 15:46:07,407 - DEBUG - VN ctest-left_vn-39302189 is not present in Agent 10.0.0.57
2025-08-06 15:46:07,407 - INFO - Validated that VN ctest-left_vn-39302189 is not in any agent
2025-08-06 15:46:07,447 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-39302189 info
2025-08-06 15:46:07,447 - INFO - Deleting VN ctest-mgmt_vn-64801314
2025-08-06 15:46:07,611 - DEBUG - Response for deleting network ()
2025-08-06 15:46:07,611 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/7d90a2ae-1664-4331-8150-da459215a906
2025-08-06 15:46:07,620 - DEBUG - Response Code: 404
2025-08-06 15:46:07,620 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:07,633 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:07,658 - DEBUG - Requesting: http://10.0.0.254:8082/project/de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:07,728 - INFO - Validated that VN ctest-mgmt_vn-64801314 is not found in API Server
2025-08-06 15:46:07,756 - DEBUG - VN ctest-mgmt_vn-64801314 is not present in Agent 10.0.0.49
2025-08-06 15:46:07,771 - DEBUG - VN ctest-mgmt_vn-64801314 is not present in Agent 10.0.0.57
2025-08-06 15:46:07,771 - INFO - Validated that VN ctest-mgmt_vn-64801314 is not in any agent
2025-08-06 15:46:07,815 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-64801314 info
2025-08-06 15:46:09,097 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:46:09,097 - INFO -
2025-08-06 15:46:09,097 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-08-06 15:46:09,097 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:46:09,877 - INFO - Deleted project: ctest-TestSvcRegr-95317165, ID : de7a0743-4cae-41e4-b054-884a3585a6e3
2025-08-06 15:46:10,048 - INFO - Domain Default found not creating
2025-08-06 15:46:10,193 - INFO - Project ctest-TestSvcRegr-29966293 not found, creating it
2025-08-06 15:46:10,706 - INFO - Created Project:ctest-TestSvcRegr-29966293, ID : a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:12,147 - INFO - ================================================================================
2025-08-06 15:46:12,147 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-06 15:46:13,441 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-06 15:46:13,441 - INFO - Initial checks done. Running the testcase now
2025-08-06 15:46:13,441 - INFO -
2025-08-06 15:46:14,149 - DEBUG - Response for create_network : {'network': {'id': '5af8bb7f-9494-448e-a962-d7e0ca3e6b9f', 'name': 'ctest-mgmt_vn-20580629', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'ctest-mgmt_vn-20580629'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:14,406 - DEBUG - Response for create_subnet : {'subnet': {'id': '6df49cbf-47ba-485c-aad0-c45930ebe9f7', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': '5af8bb7f-9494-448e-a962-d7e0ca3e6b9f', 'ip_version': 4, 'cidr': '78.58.248.64/26', 'allocation_pools': [{'start': '78.58.248.66', 'end': '78.58.248.126'}], 'gateway_ip': '78.58.248.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.58.248.66', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:14,429 - INFO - Created VN ctest-mgmt_vn-20580629
2025-08-06 15:46:14,443 - DEBUG - VN ctest-mgmt_vn-20580629 UUID is 5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:14,518 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:14,827 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:14,859 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:14,876 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:14,977 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:14,990 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:15,002 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:15,010 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:15,018 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/553f4304-59ba-4b90-b97a-bc380c4d676d
2025-08-06 15:46:15,026 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:15,026 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:15,036 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:15,052 - INFO - Verified VN network id 9 for VN 5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:15,053 - INFO - Verifications in API Server for VN ctest-mgmt_vn-20580629 passed
2025-08-06 15:46:15,053 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:15,065 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:15,073 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:15,081 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/553f4304-59ba-4b90-b97a-bc380c4d676d
2025-08-06 15:46:15,094 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6555195414265742478', 'uuid-lslong': '12205555300593200031'}, 'enable': 'true', 'created': '2025-08-06T15:46:14', 'last-modified': '2025-08-06T15:46:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.749386'}
2025-08-06 15:46:15,102 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:15,108 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6555195414265742478', 'uuid-lslong': '12205555300593200031'}, 'enable': 'true', 'created': '2025-08-06T15:46:14', 'last-modified': '2025-08-06T15:46:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.769800'}
2025-08-06 15:46:15,116 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:15,122 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6555195414265742478', 'uuid-lslong': '12205555300593200031'}, 'enable': 'true', 'created': '2025-08-06T15:46:14', 'last-modified': '2025-08-06T15:46:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.777221'}
2025-08-06 15:46:15,129 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:15,130 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-20580629 passed
2025-08-06 15:46:15,130 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-20580629 in API_Server ======
2025-08-06 15:46:15,130 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:15,139 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:15,155 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:15,247 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5af8bb7f-9494-448e-a962-d7e0ca3e6b9f
2025-08-06 15:46:15,257 - DEBUG - =>VN ctest-mgmt_vn-20580629 has no policy to be verified
2025-08-06 15:46:15,257 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:15,258 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:15,258 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:15,555 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:15,622 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629?flat'}
2025-08-06 15:46:15,622 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:15,623 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:15,921 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:15,984 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629?flat'}
2025-08-06 15:46:15,984 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:15,985 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:16,294 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:16,358 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629?flat'}
2025-08-06 15:46:16,358 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-20580629 is found in opserver
2025-08-06 15:46:16,383 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:16,395 - DEBUG - VRF ids for VN ctest-mgmt_vn-20580629: {}
2025-08-06 15:46:16,656 - DEBUG - Response for create_network : {'network': {'id': '286e7df5-b4b2-4f2c-913a-463eef96d80d', 'name': 'service-left-vn-86288190', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'service-left-vn-86288190'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:16,866 - DEBUG - Response for create_subnet : {'subnet': {'id': '9ef5f1ce-05e4-417c-8508-7a902adf3da1', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': '286e7df5-b4b2-4f2c-913a-463eef96d80d', 'ip_version': 4, 'cidr': '109.132.186.0/26', 'allocation_pools': [{'start': '109.132.186.2', 'end': '109.132.186.62'}], 'gateway_ip': '109.132.186.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '109.132.186.2', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:16,888 - INFO - Created VN service-left-vn-86288190
2025-08-06 15:46:16,904 - DEBUG - VN service-left-vn-86288190 UUID is 286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:16,992 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:16,999 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:17,012 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:17,106 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,120 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,129 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:17,139 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:17,146 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/c5c27bee-594f-43e6-a28a-8577f147dfd5
2025-08-06 15:46:17,154 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:17,154 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,166 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:17,184 - INFO - Verified VN network id 10 for VN 286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,184 - INFO - Verifications in API Server for VN service-left-vn-86288190 passed
2025-08-06 15:46:17,184 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,197 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:17,205 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:17,213 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/c5c27bee-594f-43e6-a28a-8577f147dfd5
2025-08-06 15:46:17,227 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2913404503207333676', 'uuid-lslong': '10464753920270325773'}, 'enable': 'true', 'created': '2025-08-06T15:46:16', 'last-modified': '2025-08-06T15:46:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421391'}
2025-08-06 15:46:17,233 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:17,240 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2913404503207333676', 'uuid-lslong': '10464753920270325773'}, 'enable': 'true', 'created': '2025-08-06T15:46:16', 'last-modified': '2025-08-06T15:46:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435859'}
2025-08-06 15:46:17,246 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:17,252 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2913404503207333676', 'uuid-lslong': '10464753920270325773'}, 'enable': 'true', 'created': '2025-08-06T15:46:16', 'last-modified': '2025-08-06T15:46:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441277'}
2025-08-06 15:46:17,259 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:17,259 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-86288190 passed
2025-08-06 15:46:17,260 - DEBUG - ====Verifying policy data for service-left-vn-86288190 in API_Server ======
2025-08-06 15:46:17,260 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:17,269 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:17,283 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:17,369 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/286e7df5-b4b2-4f2c-913a-463eef96d80d
2025-08-06 15:46:17,380 - DEBUG - =>VN service-left-vn-86288190 has no policy to be verified
2025-08-06 15:46:17,380 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:17,380 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:17,380 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:17,438 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190?flat'}
2025-08-06 15:46:17,439 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:17,439 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:17,496 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190?flat'}
2025-08-06 15:46:17,496 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:17,496 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:17,556 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190?flat'}
2025-08-06 15:46:17,557 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:service-left-vn-86288190 is found in opserver
2025-08-06 15:46:17,582 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:17,595 - DEBUG - VRF ids for VN service-left-vn-86288190: {}
2025-08-06 15:46:17,855 - DEBUG - Response for create_network : {'network': {'id': 'a4cb08b8-b259-4ba2-8926-985f7f4224d1', 'name': 'service-right-vn-86524746', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'service-right-vn-86524746'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:18,065 - DEBUG - Response for create_subnet : {'subnet': {'id': '3622b3be-3e6f-48bf-81d2-4650e6118a24', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': 'a4cb08b8-b259-4ba2-8926-985f7f4224d1', 'ip_version': 4, 'cidr': '21.236.32.0/26', 'allocation_pools': [{'start': '21.236.32.2', 'end': '21.236.32.62'}], 'gateway_ip': '21.236.32.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '21.236.32.2', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:18,088 - INFO - Created VN service-right-vn-86524746
2025-08-06 15:46:18,106 - DEBUG - VN service-right-vn-86524746 UUID is a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,189 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:18,197 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:18,210 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:18,312 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,326 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,337 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:18,345 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:18,352 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f19f9097-5044-4004-8632-35930fe71ec1
2025-08-06 15:46:18,361 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:18,361 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,372 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:18,389 - INFO - Verified VN network id 11 for VN a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,389 - INFO - Verifications in API Server for VN service-right-vn-86524746 passed
2025-08-06 15:46:18,389 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,401 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:18,410 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:18,418 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/f19f9097-5044-4004-8632-35930fe71ec1
2025-08-06 15:46:18,435 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11874594431851645858', 'uuid-lslong': '9882753968235488465'}, 'enable': 'true', 'created': '2025-08-06T15:46:17', 'last-modified': '2025-08-06T15:46:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.436849'}
2025-08-06 15:46:18,442 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:18,448 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11874594431851645858', 'uuid-lslong': '9882753968235488465'}, 'enable': 'true', 'created': '2025-08-06T15:46:17', 'last-modified': '2025-08-06T15:46:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.451742'}
2025-08-06 15:46:18,458 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:18,467 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11874594431851645858', 'uuid-lslong': '9882753968235488465'}, 'enable': 'true', 'created': '2025-08-06T15:46:17', 'last-modified': '2025-08-06T15:46:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.465256'}
2025-08-06 15:46:18,475 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:18,475 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-86524746 passed
2025-08-06 15:46:18,475 - DEBUG - ====Verifying policy data for service-right-vn-86524746 in API_Server ======
2025-08-06 15:46:18,475 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:18,482 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:18,496 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:18,594 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a4cb08b8-b259-4ba2-8926-985f7f4224d1
2025-08-06 15:46:18,609 - DEBUG - =>VN service-right-vn-86524746 has no policy to be verified
2025-08-06 15:46:18,609 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:18,609 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:18,609 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:18,666 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746?flat'}
2025-08-06 15:46:18,666 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:18,666 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:18,732 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746?flat'}
2025-08-06 15:46:18,732 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:18,732 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:18,789 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746?flat'}
2025-08-06 15:46:18,789 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:service-right-vn-86524746 is found in opserver
2025-08-06 15:46:18,816 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:18,832 - DEBUG - VRF ids for VN service-right-vn-86524746: {}
2025-08-06 15:46:18,944 - DEBUG - Services list from nova: [, , , ]
2025-08-06 15:46:20,052 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:46:21,438 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2)
2025-08-06 15:46:21,438 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-72026695']
2025-08-06 15:46:21,750 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-72026695'] created succesfully
2025-08-06 15:46:22,188 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 15:46:22,188 - DEBUG - Installing image tiny_nat_fw
2025-08-06 15:46:22,188 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-06 15:46:22,188 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:22,189 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:22,189 - DEBUG - Unzip image
2025-08-06 15:46:22,189 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:22,211 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 15:46:22,211 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-72026695']
2025-08-06 15:46:22,346 - INFO - Service template: ['default-domain', 'ctest-service_template_1-72026695'] deleted successfully.
2025-08-06 15:46:22,346 - INFO - Skip interface_detach for VM ctest-right_vm-52217786 in state BUILD
2025-08-06 15:46:22,346 - INFO - Deleting VM ctest-right_vm-52217786
2025-08-06 15:46:22,377 - ERROR - VM ctest-right_vm-52217786 has failed to come up
2025-08-06 15:46:22,377 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:22,377 - ERROR - VM ctest-right_vm-52217786 has failed to come up
2025-08-06 15:46:22,377 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:22,502 - INFO - Skip interface_detach for VM ctest-left_vm-37688981 in state BUILD
2025-08-06 15:46:22,502 - INFO - Deleting VM ctest-left_vm-37688981
2025-08-06 15:46:22,502 - ERROR - VM ctest-left_vm-37688981 has failed to come up
2025-08-06 15:46:22,502 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:22,502 - ERROR - VM ctest-left_vm-37688981 has failed to come up
2025-08-06 15:46:22,502 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:22,591 - INFO - Deleting VN service-right-vn-86524746
2025-08-06 15:46:22,638 - DEBUG - VN a4cb08b8-b259-4ba2-8926-985f7f4224d1 still in use: Unable to complete operation on network a4cb08b8-b259-4ba2-8926-985f7f4224d1. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4c784ac6-3300-4095-8df2-a592fca58cc4']
2025-08-06 15:46:22,638 - WARNING - Deleting VN service-right-vn-86524746 failed..Will retry
2025-08-06 15:46:24,807 - DEBUG - Response for deleting network ()
2025-08-06 15:46:24,807 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/31b4b78e-3eca-48e6-ae30-bf735d3c4b2a
2025-08-06 15:46:24,813 - DEBUG - Response Code: 404
2025-08-06 15:46:24,813 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:24,820 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:24,852 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:24,952 - INFO - Validated that VN service-right-vn-86524746 is not found in API Server
2025-08-06 15:46:24,967 - DEBUG - VN service-right-vn-86524746 is not present in Agent 10.0.0.49
2025-08-06 15:46:24,982 - DEBUG - VN service-right-vn-86524746 is not present in Agent 10.0.0.57
2025-08-06 15:46:24,983 - INFO - Validated that VN service-right-vn-86524746 is not in any agent
2025-08-06 15:46:25,027 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-86524746 info
2025-08-06 15:46:25,028 - INFO - Deleting VN service-left-vn-86288190
2025-08-06 15:46:25,226 - DEBUG - Response for deleting network ()
2025-08-06 15:46:25,226 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/0adbe5ae-1e59-45c3-8c3b-f03f3e7e4e36
2025-08-06 15:46:25,231 - DEBUG - Response Code: 404
2025-08-06 15:46:25,231 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:25,243 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:25,256 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:25,359 - INFO - Validated that VN service-left-vn-86288190 is not found in API Server
2025-08-06 15:46:25,373 - DEBUG - VN service-left-vn-86288190 is not present in Agent 10.0.0.49
2025-08-06 15:46:25,390 - DEBUG - VN service-left-vn-86288190 is not present in Agent 10.0.0.57
2025-08-06 15:46:25,390 - INFO - Validated that VN service-left-vn-86288190 is not in any agent
2025-08-06 15:46:25,434 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-86288190 info
2025-08-06 15:46:25,434 - INFO - Deleting VN ctest-mgmt_vn-20580629
2025-08-06 15:46:25,575 - DEBUG - Response for deleting network ()
2025-08-06 15:46:25,575 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/3db0ad0e-17df-48d0-93d7-21b596c02493
2025-08-06 15:46:25,582 - DEBUG - Response Code: 404
2025-08-06 15:46:25,582 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:25,591 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:25,605 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:25,701 - INFO - Validated that VN ctest-mgmt_vn-20580629 is not found in API Server
2025-08-06 15:46:25,715 - DEBUG - VN ctest-mgmt_vn-20580629 is not present in Agent 10.0.0.49
2025-08-06 15:46:25,733 - DEBUG - VN ctest-mgmt_vn-20580629 is not present in Agent 10.0.0.57
2025-08-06 15:46:25,733 - INFO - Validated that VN ctest-mgmt_vn-20580629 is not in any agent
2025-08-06 15:46:25,771 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-20580629 info
2025-08-06 15:46:27,073 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:46:27,073 - INFO -
2025-08-06 15:46:27,073 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-08-06 15:46:27,073 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:46:27,077 - INFO - ================================================================================
2025-08-06 15:46:27,077 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-06 15:46:28,347 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.57': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-08-06 15:46:28,347 - INFO - Initial checks done. Running the testcase now
2025-08-06 15:46:28,347 - INFO -
2025-08-06 15:46:28,610 - DEBUG - Response for create_network : {'network': {'id': '291e0643-a501-47d3-bcd1-0480a28d4d1b', 'name': 'ctest-mgmt_vn-87946063', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'ctest-mgmt_vn-87946063'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:28,821 - DEBUG - Response for create_subnet : {'subnet': {'id': 'db9f9715-5a80-4d2b-897c-8a77605d9e34', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': '291e0643-a501-47d3-bcd1-0480a28d4d1b', 'ip_version': 4, 'cidr': '209.135.134.128/26', 'allocation_pools': [{'start': '209.135.134.130', 'end': '209.135.134.190'}], 'gateway_ip': '209.135.134.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '209.135.134.130', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:28,848 - INFO - Created VN ctest-mgmt_vn-87946063
2025-08-06 15:46:28,859 - DEBUG - VN ctest-mgmt_vn-87946063 UUID is 291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:28,937 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:28,944 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:28,958 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:29,052 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,069 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,079 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:29,088 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:29,095 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/388d73ca-1d75-4751-ba01-0fd509f18cef
2025-08-06 15:46:29,105 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:29,105 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,116 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:29,136 - INFO - Verified VN network id 9 for VN 291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,136 - INFO - Verifications in API Server for VN ctest-mgmt_vn-87946063 passed
2025-08-06 15:46:29,136 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,148 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:29,157 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:29,165 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/388d73ca-1d75-4751-ba01-0fd509f18cef
2025-08-06 15:46:29,177 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2962812492457265107', 'uuid-lslong': '13605660899792473371'}, 'enable': 'true', 'created': '2025-08-06T15:46:28', 'last-modified': '2025-08-06T15:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.409245'}
2025-08-06 15:46:29,184 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:29,189 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2962812492457265107', 'uuid-lslong': '13605660899792473371'}, 'enable': 'true', 'created': '2025-08-06T15:46:28', 'last-modified': '2025-08-06T15:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411898'}
2025-08-06 15:46:29,196 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:29,202 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2962812492457265107', 'uuid-lslong': '13605660899792473371'}, 'enable': 'true', 'created': '2025-08-06T15:46:28', 'last-modified': '2025-08-06T15:46:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.429288'}
2025-08-06 15:46:29,209 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-06 15:46:29,209 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-87946063 passed
2025-08-06 15:46:29,209 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-87946063 in API_Server ======
2025-08-06 15:46:29,209 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:29,215 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:29,228 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:29,327 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/291e0643-a501-47d3-bcd1-0480a28d4d1b
2025-08-06 15:46:29,338 - DEBUG - =>VN ctest-mgmt_vn-87946063 has no policy to be verified
2025-08-06 15:46:29,338 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:29,338 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:29,339 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:29,352 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063?flat'}
2025-08-06 15:46:29,352 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:29,352 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:29,366 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063?flat'}
2025-08-06 15:46:29,366 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:29,366 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:29,379 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063?flat'}
2025-08-06 15:46:29,379 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:ctest-mgmt_vn-87946063 is found in opserver
2025-08-06 15:46:29,407 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:29,420 - DEBUG - VRF ids for VN ctest-mgmt_vn-87946063: {}
2025-08-06 15:46:29,686 - DEBUG - Response for create_network : {'network': {'id': '08a0757d-4966-449e-87d2-d5b0a7bcdff8', 'name': 'ctest-left_vn-26252404', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'ctest-left_vn-26252404'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:29,896 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fb1ab5d6-c135-4932-a01e-4c1259ea7e39', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': '08a0757d-4966-449e-87d2-d5b0a7bcdff8', 'ip_version': 4, 'cidr': '23.240.238.128/26', 'allocation_pools': [{'start': '23.240.238.130', 'end': '23.240.238.190'}], 'gateway_ip': '23.240.238.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23.240.238.130', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:29,921 - INFO - Created VN ctest-left_vn-26252404
2025-08-06 15:46:29,937 - DEBUG - VN ctest-left_vn-26252404 UUID is 08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,022 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:30,033 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:30,048 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:30,144 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,158 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,170 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:30,180 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:30,188 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/9685b12d-57c6-41fc-ae98-fc3ff8c57bc0
2025-08-06 15:46:30,196 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:30,196 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,206 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:30,224 - INFO - Verified VN network id 10 for VN 08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,225 - INFO - Verifications in API Server for VN ctest-left_vn-26252404 passed
2025-08-06 15:46:30,225 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,235 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:30,242 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:30,249 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/9685b12d-57c6-41fc-ae98-fc3ff8c57bc0
2025-08-06 15:46:30,262 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '621625929539929246', 'uuid-lslong': '9787119894934642680'}, 'enable': 'true', 'created': '2025-08-06T15:46:29', 'last-modified': '2025-08-06T15:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.432879'}
2025-08-06 15:46:30,269 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:30,275 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '621625929539929246', 'uuid-lslong': '9787119894934642680'}, 'enable': 'true', 'created': '2025-08-06T15:46:29', 'last-modified': '2025-08-06T15:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438565'}
2025-08-06 15:46:30,281 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:30,287 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '621625929539929246', 'uuid-lslong': '9787119894934642680'}, 'enable': 'true', 'created': '2025-08-06T15:46:29', 'last-modified': '2025-08-06T15:46:29', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447545'}
2025-08-06 15:46:30,293 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-06 15:46:30,293 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-26252404 passed
2025-08-06 15:46:30,294 - DEBUG - ====Verifying policy data for ctest-left_vn-26252404 in API_Server ======
2025-08-06 15:46:30,294 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:30,300 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:30,312 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:30,403 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/08a0757d-4966-449e-87d2-d5b0a7bcdff8
2025-08-06 15:46:30,415 - DEBUG - =>VN ctest-left_vn-26252404 has no policy to be verified
2025-08-06 15:46:30,415 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:30,415 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:30,415 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:30,428 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404?flat'}
2025-08-06 15:46:30,428 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:30,428 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:30,443 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404?flat'}
2025-08-06 15:46:30,443 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:30,444 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:30,456 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404?flat'}
2025-08-06 15:46:30,456 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:ctest-left_vn-26252404 is found in opserver
2025-08-06 15:46:30,483 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:30,497 - DEBUG - VRF ids for VN ctest-left_vn-26252404: {}
2025-08-06 15:46:30,773 - DEBUG - Response for create_network : {'network': {'id': 'e5bb413a-1517-4421-8488-18438272b439', 'name': 'ctest-right_vn-21279206', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-29966293', 'ctest-right_vn-21279206'], 'port_security_enabled': True, 'description': ''}}
2025-08-06 15:46:30,967 - DEBUG - Response for create_subnet : {'subnet': {'id': 'df51f1fb-e908-461d-b40a-403cb8b22049', 'name': '', 'tenant_id': 'a94404ea9cdd4d798c65f1c6e196f9da', 'network_id': 'e5bb413a-1517-4421-8488-18438272b439', 'ip_version': 4, 'cidr': '187.131.137.192/26', 'allocation_pools': [{'start': '187.131.137.194', 'end': '187.131.137.254'}], 'gateway_ip': '187.131.137.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '187.131.137.194', 'tags': [], 'project_id': 'a94404ea9cdd4d798c65f1c6e196f9da'}}
2025-08-06 15:46:30,994 - INFO - Created VN ctest-right_vn-21279206
2025-08-06 15:46:31,012 - DEBUG - VN ctest-right_vn-21279206 UUID is e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,101 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:31,109 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:31,123 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:31,231 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,249 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,262 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:31,273 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:31,281 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/1748c225-f063-45ac-99f7-6f4d817e8980
2025-08-06 15:46:31,301 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:31,301 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,312 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:31,335 - INFO - Verified VN network id 11 for VN e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,336 - INFO - Verifications in API Server for VN ctest-right_vn-21279206 passed
2025-08-06 15:46:31,336 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,351 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:31,361 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:31,372 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/1748c225-f063-45ac-99f7-6f4d817e8980
2025-08-06 15:46:31,390 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16553896573048144929', 'uuid-lslong': '9549909688069567545'}, 'enable': 'true', 'created': '2025-08-06T15:46:30', 'last-modified': '2025-08-06T15:46:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471662'}
2025-08-06 15:46:31,399 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:31,405 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16553896573048144929', 'uuid-lslong': '9549909688069567545'}, 'enable': 'true', 'created': '2025-08-06T15:46:30', 'last-modified': '2025-08-06T15:46:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.497377'}
2025-08-06 15:46:31,417 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:31,424 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-29966293', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16553896573048144929', 'uuid-lslong': '9549909688069567545'}, 'enable': 'true', 'created': '2025-08-06T15:46:30', 'last-modified': '2025-08-06T15:46:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.506929'}
2025-08-06 15:46:31,431 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-06 15:46:31,431 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-21279206 passed
2025-08-06 15:46:31,432 - DEBUG - ====Verifying policy data for ctest-right_vn-21279206 in API_Server ======
2025-08-06 15:46:31,432 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:31,439 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:31,452 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:31,562 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/e5bb413a-1517-4421-8488-18438272b439
2025-08-06 15:46:31,575 - DEBUG - =>VN ctest-right_vn-21279206 has no policy to be verified
2025-08-06 15:46:31,575 - DEBUG - Verifying the vn in opserver
2025-08-06 15:46:31,575 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206 virtual network link through opserver 10.0.0.254
2025-08-06 15:46:31,575 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-06 15:46:31,590 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206?flat'}
2025-08-06 15:46:31,590 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206 virtual network link through opserver 10.0.0.38
2025-08-06 15:46:31,590 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-06 15:46:31,603 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206?flat'}
2025-08-06 15:46:31,603 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206 virtual network link through opserver 10.0.0.48
2025-08-06 15:46:31,603 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-08-06 15:46:31,620 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206?flat'}
2025-08-06 15:46:31,620 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-29966293:ctest-right_vn-21279206 is found in opserver
2025-08-06 15:46:31,649 - DEBUG - Do not have enough data to verify VN in agent
2025-08-06 15:46:31,663 - DEBUG - VRF ids for VN ctest-right_vn-21279206: {}
2025-08-06 15:46:32,817 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2)
2025-08-06 15:46:34,373 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1)
2025-08-06 15:46:34,373 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-64463535']
2025-08-06 15:46:34,674 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-64463535'] created succesfully
2025-08-06 15:46:35,410 - DEBUG - Image by name tiny_nat_fw not found
2025-08-06 15:46:35,410 - DEBUG - Installing image tiny_nat_fw
2025-08-06 15:46:35,410 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-06 15:46:35,411 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:35,411 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:35,411 - DEBUG - Unzip image
2025-08-06 15:46:35,411 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-06 15:46:35,431 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-06 15:46:35,431 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-64463535']
2025-08-06 15:46:35,539 - INFO - Service template: ['default-domain', 'ctest-service_template_1-64463535'] deleted successfully.
2025-08-06 15:46:35,539 - INFO - Skip interface_detach for VM ctest-right_vm-24609841 in state BUILD
2025-08-06 15:46:35,539 - INFO - Deleting VM ctest-right_vm-24609841
2025-08-06 15:46:35,540 - ERROR - VM ctest-right_vm-24609841 has failed to come up
2025-08-06 15:46:35,540 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:35,540 - ERROR - VM ctest-right_vm-24609841 has failed to come up
2025-08-06 15:46:35,540 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:35,614 - INFO - Skip interface_detach for VM ctest-left_vm-73938971 in state BUILD
2025-08-06 15:46:35,614 - INFO - Deleting VM ctest-left_vm-73938971
2025-08-06 15:46:35,614 - ERROR - VM ctest-left_vm-73938971 has failed to come up
2025-08-06 15:46:35,614 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:35,614 - ERROR - VM ctest-left_vm-73938971 has failed to come up
2025-08-06 15:46:35,614 - ERROR - Nova failed to get host of the VM
2025-08-06 15:46:35,722 - INFO - Deleting VN ctest-right_vn-21279206
2025-08-06 15:46:35,773 - DEBUG - VN e5bb413a-1517-4421-8488-18438272b439 still in use: Unable to complete operation on network e5bb413a-1517-4421-8488-18438272b439. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2e4c3caa-8206-41a6-b531-b6cdeccd4895']
2025-08-06 15:46:35,773 - WARNING - Deleting VN ctest-right_vn-21279206 failed..Will retry
2025-08-06 15:46:37,962 - DEBUG - Response for deleting network ()
2025-08-06 15:46:37,962 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/05f75751-f4e0-4d40-b666-eb774eba459a
2025-08-06 15:46:37,970 - DEBUG - Response Code: 404
2025-08-06 15:46:37,970 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:37,978 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:38,035 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:38,130 - INFO - Validated that VN ctest-right_vn-21279206 is not found in API Server
2025-08-06 15:46:38,144 - DEBUG - VN ctest-right_vn-21279206 is not present in Agent 10.0.0.49
2025-08-06 15:46:38,159 - DEBUG - VN ctest-right_vn-21279206 is not present in Agent 10.0.0.57
2025-08-06 15:46:38,159 - INFO - Validated that VN ctest-right_vn-21279206 is not in any agent
2025-08-06 15:46:38,197 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-21279206 info
2025-08-06 15:46:38,198 - INFO - Deleting VN ctest-left_vn-26252404
2025-08-06 15:46:38,367 - DEBUG - Response for deleting network ()
2025-08-06 15:46:38,367 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6bef010b-94f5-4fc5-b21d-48f280bcc714
2025-08-06 15:46:38,374 - DEBUG - Response Code: 404
2025-08-06 15:46:38,374 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:38,388 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:38,402 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:38,520 - INFO - Validated that VN ctest-left_vn-26252404 is not found in API Server
2025-08-06 15:46:38,535 - DEBUG - VN ctest-left_vn-26252404 is not present in Agent 10.0.0.49
2025-08-06 15:46:38,549 - DEBUG - VN ctest-left_vn-26252404 is not present in Agent 10.0.0.57
2025-08-06 15:46:38,549 - INFO - Validated that VN ctest-left_vn-26252404 is not in any agent
2025-08-06 15:46:38,589 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-26252404 info
2025-08-06 15:46:38,589 - INFO - Deleting VN ctest-mgmt_vn-87946063
2025-08-06 15:46:38,770 - DEBUG - Response for deleting network ()
2025-08-06 15:46:38,770 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6b61b40c-0904-450b-9ced-bfdebeeb52f2
2025-08-06 15:46:38,777 - DEBUG - Response Code: 404
2025-08-06 15:46:38,777 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-06 15:46:38,804 - DEBUG - Requesting: http://10.0.0.254:8082/domain/024378da-7408-4369-ac73-51fa8d4d8d77
2025-08-06 15:46:38,826 - DEBUG - Requesting: http://10.0.0.254:8082/project/a94404ea-9cdd-4d79-8c65-f1c6e196f9da
2025-08-06 15:46:38,916 - INFO - Validated that VN ctest-mgmt_vn-87946063 is not found in API Server
2025-08-06 15:46:38,930 - DEBUG - VN ctest-mgmt_vn-87946063 is not present in Agent 10.0.0.49
2025-08-06 15:46:38,948 - DEBUG - VN ctest-mgmt_vn-87946063 is not present in Agent 10.0.0.57
2025-08-06 15:46:38,948 - INFO - Validated that VN ctest-mgmt_vn-87946063 is not in any agent
2025-08-06 15:46:38,993 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-87946063 info
2025-08-06 15:46:40,286 - DEBUG - No XMPP flaps were noticed during the test
2025-08-06 15:46:40,286 - INFO -
2025-08-06 15:46:40,286 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:13]
2025-08-06 15:46:40,286 - INFO - --------------------------------------------------------------------------------
2025-08-06 15:46:41,022 - INFO - Deleted project: ctest-TestSvcRegr-29966293, ID : a94404ea-9cdd-4d79-8c65-f1c6e196f9da