]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 03:06:02,414 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-97020475']
2025-10-09 03:06:02,720 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-97020475'] created succesfully
2025-10-09 03:06:03,190 - DEBUG - Image by name tiny_nat_fw not found
2025-10-09 03:06:03,190 - DEBUG - Installing image tiny_nat_fw
2025-10-09 03:06:03,190 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-09 03:06:03,190 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:03,190 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:03,213 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:03,349 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:03,349 - DEBUG - Unzip image
2025-10-09 03:06:03,349 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:03,377 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-09 03:06:03,378 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-97020475']
2025-10-09 03:06:03,473 - INFO - Service template: ['default-domain', 'ctest-service_template_1-97020475'] deleted successfully.
2025-10-09 03:06:03,474 - INFO - Skip interface_detach for VM ctest-right_vm-07045972 in state BUILD
2025-10-09 03:06:03,474 - INFO - Deleting VM ctest-right_vm-07045972
2025-10-09 03:06:03,515 - ERROR - VM ctest-right_vm-07045972 has failed to come up
2025-10-09 03:06:03,515 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:03,515 - ERROR - VM ctest-right_vm-07045972 has failed to come up
2025-10-09 03:06:03,515 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:03,584 - INFO - Skip interface_detach for VM ctest-left_vm-09082442 in state BUILD
2025-10-09 03:06:03,585 - INFO - Deleting VM ctest-left_vm-09082442
2025-10-09 03:06:03,585 - ERROR - VM ctest-left_vm-09082442 has failed to come up
2025-10-09 03:06:03,585 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:03,585 - ERROR - VM ctest-left_vm-09082442 has failed to come up
2025-10-09 03:06:03,585 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:03,658 - INFO - Deleting VN ctest-right_vn-11223602
2025-10-09 03:06:03,720 - DEBUG - VN e469933f-5148-432b-94db-6479a4dbffad still in use: Unable to complete operation on network e469933f-5148-432b-94db-6479a4dbffad. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-013bfd63-f06b-43b2-adf7-3fc68480e830']
2025-10-09 03:06:03,720 - WARNING - Deleting VN ctest-right_vn-11223602 failed..Will retry
2025-10-09 03:06:05,898 - DEBUG - Response for deleting network ()
2025-10-09 03:06:05,898 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/fa091907-9efe-4ae6-a6e6-ddef58f6ad2f
2025-10-09 03:06:05,913 - DEBUG - Response Code: 404
2025-10-09 03:06:05,913 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:05,919 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:05,932 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:06,021 - INFO - Validated that VN ctest-right_vn-11223602 is not found in API Server
2025-10-09 03:06:06,032 - DEBUG - VN ctest-right_vn-11223602 is not present in Agent 10.0.0.22
2025-10-09 03:06:06,090 - DEBUG - VN ctest-right_vn-11223602 is not present in Agent 10.0.0.27
2025-10-09 03:06:06,090 - INFO - Validated that VN ctest-right_vn-11223602 is not in any agent
2025-10-09 03:06:06,129 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-11223602 info
2025-10-09 03:06:06,129 - INFO - Deleting VN ctest-left_vn-69800283
2025-10-09 03:06:06,286 - DEBUG - Response for deleting network ()
2025-10-09 03:06:06,287 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/167b9065-a53e-4941-b54e-564bbb994d33
2025-10-09 03:06:06,293 - DEBUG - Response Code: 404
2025-10-09 03:06:06,293 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:06,306 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:06,339 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:06,434 - INFO - Validated that VN ctest-left_vn-69800283 is not found in API Server
2025-10-09 03:06:06,445 - DEBUG - VN ctest-left_vn-69800283 is not present in Agent 10.0.0.22
2025-10-09 03:06:06,460 - DEBUG - VN ctest-left_vn-69800283 is not present in Agent 10.0.0.27
2025-10-09 03:06:06,460 - INFO - Validated that VN ctest-left_vn-69800283 is not in any agent
2025-10-09 03:06:06,501 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-69800283 info
2025-10-09 03:06:06,501 - INFO - Deleting VN ctest-mgmt_vn-59770349
2025-10-09 03:06:06,651 - DEBUG - Response for deleting network ()
2025-10-09 03:06:06,652 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/65533073-ba91-4277-976c-23787894f030
2025-10-09 03:06:06,658 - DEBUG - Response Code: 404
2025-10-09 03:06:06,658 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:06,666 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:06,683 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:06,784 - INFO - Validated that VN ctest-mgmt_vn-59770349 is not found in API Server
2025-10-09 03:06:06,794 - DEBUG - VN ctest-mgmt_vn-59770349 is not present in Agent 10.0.0.22
2025-10-09 03:06:06,808 - DEBUG - VN ctest-mgmt_vn-59770349 is not present in Agent 10.0.0.27
2025-10-09 03:06:06,808 - INFO - Validated that VN ctest-mgmt_vn-59770349 is not in any agent
2025-10-09 03:06:06,845 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-59770349 info
2025-10-09 03:06:06,845 - INFO - Deleting VM ctest-vm-in-third-vn-00936685
2025-10-09 03:06:06,912 - INFO - Deleting VN ctest-third-vn-33173323
2025-10-09 03:06:06,966 - DEBUG - VN fc3c60f7-7c43-4245-9560-ac2b6db4be58 still in use: Unable to complete operation on network fc3c60f7-7c43-4245-9560-ac2b6db4be58. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cf6f16f8-7f03-497a-b753-30febb52ffda']
2025-10-09 03:06:06,966 - WARNING - Deleting VN ctest-third-vn-33173323 failed..Will retry
2025-10-09 03:06:09,114 - DEBUG - Response for deleting network ()
2025-10-09 03:06:10,410 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 03:06:10,410 - INFO -
2025-10-09 03:06:10,410 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:24]
2025-10-09 03:06:10,410 - INFO - --------------------------------------------------------------------------------
2025-10-09 03:06:10,413 - INFO - ================================================================================
2025-10-09 03:06:10,413 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-10-09 03:06:11,678 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.27': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-10-09 03:06:11,678 - INFO - Initial checks done. Running the testcase now
2025-10-09 03:06:11,678 - INFO -
2025-10-09 03:06:11,961 - DEBUG - Response for create_network : {'network': {'id': '1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780', 'name': 'ctest-mgmt_vn-00947624', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'project_id': 'ecebefcdc95b4a06900b41e26670681c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94743253', 'ctest-mgmt_vn-00947624'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:12,170 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd25a7bb6-afdb-43cc-bff4-c746f5710327', 'name': '', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'network_id': '1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780', 'ip_version': 4, 'cidr': '28.218.36.64/26', 'allocation_pools': [{'start': '28.218.36.66', 'end': '28.218.36.126'}], 'gateway_ip': '28.218.36.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28.218.36.66', 'tags': [], 'project_id': 'ecebefcdc95b4a06900b41e26670681c'}}
2025-10-09 03:06:12,195 - INFO - Created VN ctest-mgmt_vn-00947624
2025-10-09 03:06:12,205 - DEBUG - VN ctest-mgmt_vn-00947624 UUID is 1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,282 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:12,287 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:12,326 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:12,430 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,442 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,452 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:12,459 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:12,465 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/67122058-5b78-4abc-be1c-9c071b4e115e
2025-10-09 03:06:12,471 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:12,471 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,479 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:12,497 - INFO - Verified VN network id 14 for VN 1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,497 - INFO - Verifications in API Server for VN ctest-mgmt_vn-00947624 passed
2025-10-09 03:06:12,497 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,505 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:12,515 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:12,521 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/67122058-5b78-4abc-be1c-9c071b4e115e
2025-10-09 03:06:12,534 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1881510071489217787', 'uuid-lslong': '11320464636776490880'}, 'enable': 'true', 'created': '2025-10-09T03:06:11', 'last-modified': '2025-10-09T03:06:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416991'}
2025-10-09 03:06:12,543 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:12,550 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1881510071489217787', 'uuid-lslong': '11320464636776490880'}, 'enable': 'true', 'created': '2025-10-09T03:06:11', 'last-modified': '2025-10-09T03:06:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433777'}
2025-10-09 03:06:12,557 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:12,563 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1881510071489217787', 'uuid-lslong': '11320464636776490880'}, 'enable': 'true', 'created': '2025-10-09T03:06:11', 'last-modified': '2025-10-09T03:06:12', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.446269'}
2025-10-09 03:06:12,569 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:12,569 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-00947624 passed
2025-10-09 03:06:12,569 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-00947624 in API_Server ======
2025-10-09 03:06:12,569 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:12,576 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:12,591 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:12,691 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1a1c7770-fc6f-44fb-9d1a-5e9bd76f2780
2025-10-09 03:06:12,704 - DEBUG - =>VN ctest-mgmt_vn-00947624 has no policy to be verified
2025-10-09 03:06:12,705 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:12,705 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:12,705 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:12,717 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624?flat'}
2025-10-09 03:06:12,717 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:12,718 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:12,731 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624?flat'}
2025-10-09 03:06:12,731 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:12,731 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:12,744 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624?flat'}
2025-10-09 03:06:12,744 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94743253:ctest-mgmt_vn-00947624 is found in opserver
2025-10-09 03:06:12,764 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:12,772 - DEBUG - VRF ids for VN ctest-mgmt_vn-00947624: {}
2025-10-09 03:06:13,038 - DEBUG - Response for create_network : {'network': {'id': 'f96ae3a6-b040-4dff-85ce-515005c6d701', 'name': 'ctest-left_vn-59444061', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'project_id': 'ecebefcdc95b4a06900b41e26670681c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94743253', 'ctest-left_vn-59444061'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:13,242 - DEBUG - Response for create_subnet : {'subnet': {'id': '1afe46c8-86f3-4fa5-8fd7-026322716dde', 'name': '', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'network_id': 'f96ae3a6-b040-4dff-85ce-515005c6d701', 'ip_version': 4, 'cidr': '66.146.241.0/26', 'allocation_pools': [{'start': '66.146.241.2', 'end': '66.146.241.62'}], 'gateway_ip': '66.146.241.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '66.146.241.2', 'tags': [], 'project_id': 'ecebefcdc95b4a06900b41e26670681c'}}
2025-10-09 03:06:13,266 - INFO - Created VN ctest-left_vn-59444061
2025-10-09 03:06:13,278 - DEBUG - VN ctest-left_vn-59444061 UUID is f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,344 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:13,352 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:13,362 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:13,452 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,463 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,471 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:13,478 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:13,487 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/d8c2455f-d62b-4890-a474-56c8fcdd35d3
2025-10-09 03:06:13,495 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:13,495 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,504 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:13,530 - INFO - Verified VN network id 15 for VN f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,531 - INFO - Verifications in API Server for VN ctest-left_vn-59444061 passed
2025-10-09 03:06:13,531 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,540 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:13,548 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:13,555 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/d8c2455f-d62b-4890-a474-56c8fcdd35d3
2025-10-09 03:06:13,567 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17972427568036466175', 'uuid-lslong': '9641733256382961409'}, 'enable': 'true', 'created': '2025-10-09T03:06:12', 'last-modified': '2025-10-09T03:06:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369597'}
2025-10-09 03:06:13,573 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:13,578 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17972427568036466175', 'uuid-lslong': '9641733256382961409'}, 'enable': 'true', 'created': '2025-10-09T03:06:12', 'last-modified': '2025-10-09T03:06:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383843'}
2025-10-09 03:06:13,586 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:13,592 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17972427568036466175', 'uuid-lslong': '9641733256382961409'}, 'enable': 'true', 'created': '2025-10-09T03:06:12', 'last-modified': '2025-10-09T03:06:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.399552'}
2025-10-09 03:06:13,600 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:13,600 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-59444061 passed
2025-10-09 03:06:13,600 - DEBUG - ====Verifying policy data for ctest-left_vn-59444061 in API_Server ======
2025-10-09 03:06:13,600 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:13,608 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:13,622 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:13,716 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f96ae3a6-b040-4dff-85ce-515005c6d701
2025-10-09 03:06:13,730 - DEBUG - =>VN ctest-left_vn-59444061 has no policy to be verified
2025-10-09 03:06:13,730 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:13,730 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:13,730 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:13,743 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061?flat'}
2025-10-09 03:06:13,743 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:13,743 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:13,755 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061?flat'}
2025-10-09 03:06:13,755 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:13,756 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:13,768 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061?flat'}
2025-10-09 03:06:13,768 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94743253:ctest-left_vn-59444061 is found in opserver
2025-10-09 03:06:13,787 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:13,796 - DEBUG - VRF ids for VN ctest-left_vn-59444061: {}
2025-10-09 03:06:14,077 - DEBUG - Response for create_network : {'network': {'id': 'df5a6acd-c9f4-4b32-a290-e90e87880d52', 'name': 'ctest-right_vn-54431590', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'project_id': 'ecebefcdc95b4a06900b41e26670681c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94743253', 'ctest-right_vn-54431590'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:14,323 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e87cfb22-0c12-4f06-a1fe-d9cd7545e9d8', 'name': '', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'network_id': 'df5a6acd-c9f4-4b32-a290-e90e87880d52', 'ip_version': 4, 'cidr': '63.6.86.128/26', 'allocation_pools': [{'start': '63.6.86.130', 'end': '63.6.86.190'}], 'gateway_ip': '63.6.86.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '63.6.86.130', 'tags': [], 'project_id': 'ecebefcdc95b4a06900b41e26670681c'}}
2025-10-09 03:06:14,345 - INFO - Created VN ctest-right_vn-54431590
2025-10-09 03:06:14,355 - DEBUG - VN ctest-right_vn-54431590 UUID is df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,444 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:14,453 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:14,464 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:14,599 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,619 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,637 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:14,648 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:14,669 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/398467f5-140c-4241-ba71-5b01ee7587de
2025-10-09 03:06:14,686 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:14,686 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,698 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:14,714 - INFO - Verified VN network id 16 for VN df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,714 - INFO - Verifications in API Server for VN ctest-right_vn-54431590 passed
2025-10-09 03:06:14,715 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,730 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:14,739 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:14,753 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/398467f5-140c-4241-ba71-5b01ee7587de
2025-10-09 03:06:14,766 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16094293650450959154', 'uuid-lslong': '11714118879403314514'}, 'enable': 'true', 'created': '2025-10-09T03:06:14', 'last-modified': '2025-10-09T03:06:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.525730'}
2025-10-09 03:06:14,778 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:14,786 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16094293650450959154', 'uuid-lslong': '11714118879403314514'}, 'enable': 'true', 'created': '2025-10-09T03:06:14', 'last-modified': '2025-10-09T03:06:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.551402'}
2025-10-09 03:06:14,793 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:14,799 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16094293650450959154', 'uuid-lslong': '11714118879403314514'}, 'enable': 'true', 'created': '2025-10-09T03:06:14', 'last-modified': '2025-10-09T03:06:14', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.563414'}
2025-10-09 03:06:14,804 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:14,804 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-54431590 passed
2025-10-09 03:06:14,805 - DEBUG - ====Verifying policy data for ctest-right_vn-54431590 in API_Server ======
2025-10-09 03:06:14,805 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:14,812 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:14,824 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:14,916 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/df5a6acd-c9f4-4b32-a290-e90e87880d52
2025-10-09 03:06:14,925 - DEBUG - =>VN ctest-right_vn-54431590 has no policy to be verified
2025-10-09 03:06:14,925 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:14,925 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:14,925 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:14,937 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590?flat'}
2025-10-09 03:06:14,937 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:14,938 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:14,949 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590?flat'}
2025-10-09 03:06:14,950 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:14,950 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:14,962 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590?flat'}
2025-10-09 03:06:14,962 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94743253:ctest-right_vn-54431590 is found in opserver
2025-10-09 03:06:14,986 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:14,999 - DEBUG - VRF ids for VN ctest-right_vn-54431590: {}
2025-10-09 03:06:15,298 - DEBUG - Response for create_network : {'network': {'id': '9bc9a451-ed77-4fd3-97d6-b74c0db198e7', 'name': 'ctest-trans_left_vn-00640305', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'project_id': 'ecebefcdc95b4a06900b41e26670681c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94743253', 'ctest-trans_left_vn-00640305'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:15,507 - DEBUG - Response for create_subnet : {'subnet': {'id': '3d6959d2-1976-4adc-98d7-09e0318e19ce', 'name': '', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'network_id': '9bc9a451-ed77-4fd3-97d6-b74c0db198e7', 'ip_version': 4, 'cidr': '81.34.6.192/26', 'allocation_pools': [{'start': '81.34.6.194', 'end': '81.34.6.254'}], 'gateway_ip': '81.34.6.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '81.34.6.194', 'tags': [], 'project_id': 'ecebefcdc95b4a06900b41e26670681c'}}
2025-10-09 03:06:15,543 - INFO - Created VN ctest-trans_left_vn-00640305
2025-10-09 03:06:15,555 - DEBUG - VN ctest-trans_left_vn-00640305 UUID is 9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,633 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:15,641 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:15,662 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:15,759 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,773 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,782 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:15,789 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:15,796 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4fb5596b-43b2-4d5a-91db-be3055913b11
2025-10-09 03:06:15,803 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-09 03:06:15,803 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,813 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:15,829 - INFO - Verified VN network id 17 for VN 9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,829 - INFO - Verifications in API Server for VN ctest-trans_left_vn-00640305 passed
2025-10-09 03:06:15,829 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:15,838 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:15,845 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:15,852 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4fb5596b-43b2-4d5a-91db-be3055913b11
2025-10-09 03:06:15,868 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11225684217980997587', 'uuid-lslong': '10941133882018339047'}, 'enable': 'true', 'created': '2025-10-09T03:06:15', 'last-modified': '2025-10-09T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410366'}
2025-10-09 03:06:15,877 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-09 03:06:15,883 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11225684217980997587', 'uuid-lslong': '10941133882018339047'}, 'enable': 'true', 'created': '2025-10-09T03:06:15', 'last-modified': '2025-10-09T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426534'}
2025-10-09 03:06:15,889 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-09 03:06:15,895 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11225684217980997587', 'uuid-lslong': '10941133882018339047'}, 'enable': 'true', 'created': '2025-10-09T03:06:15', 'last-modified': '2025-10-09T03:06:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.438281'}
2025-10-09 03:06:15,900 - DEBUG - Route Targets: ['target:64512:8000007']
2025-10-09 03:06:15,901 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-00640305 passed
2025-10-09 03:06:15,901 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-00640305 in API_Server ======
2025-10-09 03:06:15,901 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:15,908 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:15,920 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:16,009 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/9bc9a451-ed77-4fd3-97d6-b74c0db198e7
2025-10-09 03:06:16,019 - DEBUG - =>VN ctest-trans_left_vn-00640305 has no policy to be verified
2025-10-09 03:06:16,019 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:16,019 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:16,019 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:16,035 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305?flat'}
2025-10-09 03:06:16,035 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:16,035 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:16,049 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305?flat'}
2025-10-09 03:06:16,049 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:16,049 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:16,064 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305?flat'}
2025-10-09 03:06:16,064 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94743253:ctest-trans_left_vn-00640305 is found in opserver
2025-10-09 03:06:16,083 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:16,091 - DEBUG - VRF ids for VN ctest-trans_left_vn-00640305: {}
2025-10-09 03:06:16,366 - DEBUG - Response for create_network : {'network': {'id': '389a0bd7-b80c-42eb-b87e-0fc5bea36f8f', 'name': 'ctest-trans_right_vn-95081391', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'project_id': 'ecebefcdc95b4a06900b41e26670681c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-94743253', 'ctest-trans_right_vn-95081391'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:16,628 - DEBUG - Response for create_subnet : {'subnet': {'id': '47814dd2-e1b5-40af-9d22-d6e4da752973', 'name': '', 'tenant_id': 'ecebefcdc95b4a06900b41e26670681c', 'network_id': '389a0bd7-b80c-42eb-b87e-0fc5bea36f8f', 'ip_version': 4, 'cidr': '155.121.32.128/26', 'allocation_pools': [{'start': '155.121.32.130', 'end': '155.121.32.190'}], 'gateway_ip': '155.121.32.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '155.121.32.130', 'tags': [], 'project_id': 'ecebefcdc95b4a06900b41e26670681c'}}
2025-10-09 03:06:16,654 - INFO - Created VN ctest-trans_right_vn-95081391
2025-10-09 03:06:16,668 - DEBUG - VN ctest-trans_right_vn-95081391 UUID is 389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,747 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:16,753 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:16,764 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:16,857 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,870 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,878 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:16,884 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:16,891 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/231f1693-994d-4a88-b3e6-2824b2742f1b
2025-10-09 03:06:16,896 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-09 03:06:16,897 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,905 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:16,922 - INFO - Verified VN network id 18 for VN 389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,922 - INFO - Verifications in API Server for VN ctest-trans_right_vn-95081391 passed
2025-10-09 03:06:16,922 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:16,933 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:16,940 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:16,952 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/231f1693-994d-4a88-b3e6-2824b2742f1b
2025-10-09 03:06:16,964 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4078585433671090923', 'uuid-lslong': '13294080492025638799'}, 'enable': 'true', 'created': '2025-10-09T03:06:16', 'last-modified': '2025-10-09T03:06:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422696'}
2025-10-09 03:06:16,972 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-09 03:06:16,979 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4078585433671090923', 'uuid-lslong': '13294080492025638799'}, 'enable': 'true', 'created': '2025-10-09T03:06:16', 'last-modified': '2025-10-09T03:06:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.439703'}
2025-10-09 03:06:16,985 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-09 03:06:16,990 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-94743253', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4078585433671090923', 'uuid-lslong': '13294080492025638799'}, 'enable': 'true', 'created': '2025-10-09T03:06:16', 'last-modified': '2025-10-09T03:06:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.447959'}
2025-10-09 03:06:16,996 - DEBUG - Route Targets: ['target:64512:8000008']
2025-10-09 03:06:16,996 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-95081391 passed
2025-10-09 03:06:16,996 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-95081391 in API_Server ======
2025-10-09 03:06:16,996 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:17,002 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:17,013 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:17,093 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/389a0bd7-b80c-42eb-b87e-0fc5bea36f8f
2025-10-09 03:06:17,115 - DEBUG - =>VN ctest-trans_right_vn-95081391 has no policy to be verified
2025-10-09 03:06:17,115 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:17,115 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:17,115 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:17,129 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391?flat'}
2025-10-09 03:06:17,129 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:17,129 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:17,142 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391?flat'}
2025-10-09 03:06:17,142 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:17,142 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:17,159 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391?flat'}
2025-10-09 03:06:17,159 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-94743253:ctest-trans_right_vn-95081391 is found in opserver
2025-10-09 03:06:17,182 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:17,191 - DEBUG - VRF ids for VN ctest-trans_right_vn-95081391: {}
2025-10-09 03:06:18,251 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 03:06:19,664 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-1)
2025-10-09 03:06:19,665 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-56648443']
2025-10-09 03:06:19,957 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-56648443'] created succesfully
2025-10-09 03:06:20,325 - DEBUG - Image by name tiny_trans_fw not found
2025-10-09 03:06:20,326 - DEBUG - Installing image tiny_trans_fw
2025-10-09 03:06:20,326 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-10-09 03:06:20,326 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-09 03:06:20,326 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-10-09 03:06:20,345 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-09 03:06:20,375 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-09 03:06:20,376 - DEBUG - Unzip image
2025-10-09 03:06:20,376 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-10-09 03:06:20,405 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-10-09 03:06:20,406 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-56648443']
2025-10-09 03:06:20,513 - INFO - Service template: ['default-domain', 'ctest-service_template_1-56648443'] deleted successfully.
2025-10-09 03:06:20,514 - INFO - Skip interface_detach for VM ctest-right_vm-44166619 in state BUILD
2025-10-09 03:06:20,514 - INFO - Deleting VM ctest-right_vm-44166619
2025-10-09 03:06:20,514 - ERROR - VM ctest-right_vm-44166619 has failed to come up
2025-10-09 03:06:20,514 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:20,515 - ERROR - VM ctest-right_vm-44166619 has failed to come up
2025-10-09 03:06:20,515 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:20,601 - INFO - Skip interface_detach for VM ctest-left_vm-17998707 in state BUILD
2025-10-09 03:06:20,601 - INFO - Deleting VM ctest-left_vm-17998707
2025-10-09 03:06:20,601 - ERROR - VM ctest-left_vm-17998707 has failed to come up
2025-10-09 03:06:20,601 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:20,601 - ERROR - VM ctest-left_vm-17998707 has failed to come up
2025-10-09 03:06:20,601 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:20,674 - INFO - Deleting VN ctest-trans_right_vn-95081391
2025-10-09 03:06:20,837 - DEBUG - Response for deleting network ()
2025-10-09 03:06:20,837 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/860edde1-1781-4e60-824b-a22508b92e13
2025-10-09 03:06:20,843 - DEBUG - Response Code: 404
2025-10-09 03:06:20,843 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:20,858 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:20,876 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:20,972 - INFO - Validated that VN ctest-trans_right_vn-95081391 is not found in API Server
2025-10-09 03:06:20,983 - DEBUG - VN ctest-trans_right_vn-95081391 is not present in Agent 10.0.0.22
2025-10-09 03:06:20,991 - DEBUG - VN ctest-trans_right_vn-95081391 is not present in Agent 10.0.0.27
2025-10-09 03:06:20,991 - INFO - Validated that VN ctest-trans_right_vn-95081391 is not in any agent
2025-10-09 03:06:21,024 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-95081391 info
2025-10-09 03:06:21,024 - INFO - Deleting VN ctest-trans_left_vn-00640305
2025-10-09 03:06:21,175 - DEBUG - Response for deleting network ()
2025-10-09 03:06:21,175 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/86dec67b-d988-49c7-a8ff-8376b439a265
2025-10-09 03:06:21,182 - DEBUG - Response Code: 404
2025-10-09 03:06:21,182 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:21,191 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:21,202 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:21,293 - INFO - Validated that VN ctest-trans_left_vn-00640305 is not found in API Server
2025-10-09 03:06:21,310 - DEBUG - VN ctest-trans_left_vn-00640305 is not present in Agent 10.0.0.22
2025-10-09 03:06:21,328 - DEBUG - VN ctest-trans_left_vn-00640305 is not present in Agent 10.0.0.27
2025-10-09 03:06:21,328 - INFO - Validated that VN ctest-trans_left_vn-00640305 is not in any agent
2025-10-09 03:06:21,376 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-00640305 info
2025-10-09 03:06:21,376 - INFO - Deleting VN ctest-right_vn-54431590
2025-10-09 03:06:21,432 - DEBUG - VN df5a6acd-c9f4-4b32-a290-e90e87880d52 still in use: Unable to complete operation on network df5a6acd-c9f4-4b32-a290-e90e87880d52. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-631ceef9-a602-4d34-81c0-6242340078b9']
2025-10-09 03:06:21,432 - WARNING - Deleting VN ctest-right_vn-54431590 failed..Will retry
2025-10-09 03:06:23,574 - DEBUG - Response for deleting network ()
2025-10-09 03:06:23,574 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/dffac49b-ce2c-4a7b-944d-3c4216df664d
2025-10-09 03:06:23,580 - DEBUG - Response Code: 404
2025-10-09 03:06:23,581 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:23,586 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:23,639 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:23,724 - INFO - Validated that VN ctest-right_vn-54431590 is not found in API Server
2025-10-09 03:06:23,736 - DEBUG - VN ctest-right_vn-54431590 is not present in Agent 10.0.0.22
2025-10-09 03:06:23,747 - DEBUG - VN ctest-right_vn-54431590 is not present in Agent 10.0.0.27
2025-10-09 03:06:23,747 - INFO - Validated that VN ctest-right_vn-54431590 is not in any agent
2025-10-09 03:06:23,774 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-54431590 info
2025-10-09 03:06:23,775 - INFO - Deleting VN ctest-left_vn-59444061
2025-10-09 03:06:23,916 - DEBUG - Response for deleting network ()
2025-10-09 03:06:23,916 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/6f4b19de-2ab3-4301-8f2a-a7db043671b2
2025-10-09 03:06:23,922 - DEBUG - Response Code: 404
2025-10-09 03:06:23,922 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:23,932 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:23,945 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:24,035 - INFO - Validated that VN ctest-left_vn-59444061 is not found in API Server
2025-10-09 03:06:24,045 - DEBUG - VN ctest-left_vn-59444061 is not present in Agent 10.0.0.22
2025-10-09 03:06:24,055 - DEBUG - VN ctest-left_vn-59444061 is not present in Agent 10.0.0.27
2025-10-09 03:06:24,055 - INFO - Validated that VN ctest-left_vn-59444061 is not in any agent
2025-10-09 03:06:24,085 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-59444061 info
2025-10-09 03:06:24,085 - INFO - Deleting VN ctest-mgmt_vn-00947624
2025-10-09 03:06:24,268 - DEBUG - Response for deleting network ()
2025-10-09 03:06:24,268 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/ae831295-76ce-463a-af86-53e932d0aa00
2025-10-09 03:06:24,274 - DEBUG - Response Code: 404
2025-10-09 03:06:24,274 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:24,280 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:24,292 - DEBUG - Requesting: http://10.0.0.56:8082/project/ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:24,377 - INFO - Validated that VN ctest-mgmt_vn-00947624 is not found in API Server
2025-10-09 03:06:24,389 - DEBUG - VN ctest-mgmt_vn-00947624 is not present in Agent 10.0.0.22
2025-10-09 03:06:24,398 - DEBUG - VN ctest-mgmt_vn-00947624 is not present in Agent 10.0.0.27
2025-10-09 03:06:24,398 - INFO - Validated that VN ctest-mgmt_vn-00947624 is not in any agent
2025-10-09 03:06:24,428 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-00947624 info
2025-10-09 03:06:25,712 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 03:06:25,712 - INFO -
2025-10-09 03:06:25,712 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:15]
2025-10-09 03:06:25,712 - INFO - --------------------------------------------------------------------------------
2025-10-09 03:06:26,450 - INFO - Deleted project: ctest-TestSvcRegr-94743253, ID : ecebefcd-c95b-4a06-900b-41e26670681c
2025-10-09 03:06:26,627 - INFO - Domain Default found not creating
2025-10-09 03:06:26,774 - INFO - Project ctest-TestSvcRegr-19286188 not found, creating it
2025-10-09 03:06:27,304 - INFO - Created Project:ctest-TestSvcRegr-19286188, ID : 8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:28,787 - INFO - ================================================================================
2025-10-09 03:06:28,787 - INFO - STARTING TEST : test_svc_CEM_22032
2025-10-09 03:06:30,078 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.27': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-10-09 03:06:30,078 - INFO - Initial checks done. Running the testcase now
2025-10-09 03:06:30,078 - INFO -
2025-10-09 03:06:30,732 - DEBUG - Response for create_network : {'network': {'id': 'e4e6827a-1ec1-4991-961d-5b5f00bf65c0', 'name': 'ctest-mgmt_vn-70718935', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'ctest-mgmt_vn-70718935'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:30,993 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f23ac810-bd8f-48ff-b1f6-43157ea4dfc6', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': 'e4e6827a-1ec1-4991-961d-5b5f00bf65c0', 'ip_version': 4, 'cidr': '83.81.77.64/26', 'allocation_pools': [{'start': '83.81.77.66', 'end': '83.81.77.126'}], 'gateway_ip': '83.81.77.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '83.81.77.66', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:31,015 - INFO - Created VN ctest-mgmt_vn-70718935
2025-10-09 03:06:31,026 - DEBUG - VN ctest-mgmt_vn-70718935 UUID is e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,100 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:31,403 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:31,433 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:31,449 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:31,545 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,557 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,566 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:31,574 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:31,582 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/80cf0bad-c466-4c95-b82e-328e54165a33
2025-10-09 03:06:31,588 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:31,588 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,598 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:31,613 - INFO - Verified VN network id 14 for VN e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,613 - INFO - Verifications in API Server for VN ctest-mgmt_vn-70718935 passed
2025-10-09 03:06:31,613 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,624 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:31,632 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:31,641 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/80cf0bad-c466-4c95-b82e-328e54165a33
2025-10-09 03:06:31,653 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16494014146304625041', 'uuid-lslong': '10816902343606363584'}, 'enable': 'true', 'created': '2025-10-09T03:06:30', 'last-modified': '2025-10-09T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.737183'}
2025-10-09 03:06:31,659 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:31,665 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16494014146304625041', 'uuid-lslong': '10816902343606363584'}, 'enable': 'true', 'created': '2025-10-09T03:06:30', 'last-modified': '2025-10-09T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.749607'}
2025-10-09 03:06:31,672 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:31,678 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16494014146304625041', 'uuid-lslong': '10816902343606363584'}, 'enable': 'true', 'created': '2025-10-09T03:06:30', 'last-modified': '2025-10-09T03:06:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.761885'}
2025-10-09 03:06:31,684 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:31,684 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-70718935 passed
2025-10-09 03:06:31,684 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-70718935 in API_Server ======
2025-10-09 03:06:31,684 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:31,690 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:31,700 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:31,793 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/e4e6827a-1ec1-4991-961d-5b5f00bf65c0
2025-10-09 03:06:31,803 - DEBUG - =>VN ctest-mgmt_vn-70718935 has no policy to be verified
2025-10-09 03:06:31,804 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:31,804 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:31,804 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,107 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,176 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935?flat'}
2025-10-09 03:06:32,176 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:32,176 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,480 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,550 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935?flat'}
2025-10-09 03:06:32,550 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:32,550 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,867 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:32,930 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935?flat'}
2025-10-09 03:06:32,930 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-70718935 is found in opserver
2025-10-09 03:06:32,950 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:32,959 - DEBUG - VRF ids for VN ctest-mgmt_vn-70718935: {}
2025-10-09 03:06:33,254 - DEBUG - Response for create_network : {'network': {'id': '7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b', 'name': 'service-left-vn-26089291', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'service-left-vn-26089291'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:33,494 - DEBUG - Response for create_subnet : {'subnet': {'id': 'af4bf48b-704d-408a-aabe-9241ab24c95b', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': '7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b', 'ip_version': 4, 'cidr': '117.208.6.128/26', 'allocation_pools': [{'start': '117.208.6.130', 'end': '117.208.6.190'}], 'gateway_ip': '117.208.6.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '117.208.6.130', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:33,515 - INFO - Created VN service-left-vn-26089291
2025-10-09 03:06:33,526 - DEBUG - VN service-left-vn-26089291 UUID is 7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,597 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:33,603 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:33,613 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:33,704 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,723 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,734 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:33,745 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:33,752 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/1d2aa18c-33c0-41e7-8d92-349b4ee3ff09
2025-10-09 03:06:33,759 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:33,759 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,769 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:33,785 - INFO - Verified VN network id 15 for VN 7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,785 - INFO - Verifications in API Server for VN service-left-vn-26089291 passed
2025-10-09 03:06:33,786 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,799 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:33,805 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:33,812 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/1d2aa18c-33c0-41e7-8d92-349b4ee3ff09
2025-10-09 03:06:33,824 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9214930412832771328', 'uuid-lslong': '11100962947503681323'}, 'enable': 'true', 'created': '2025-10-09T03:06:33', 'last-modified': '2025-10-09T03:06:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380657'}
2025-10-09 03:06:33,829 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:33,834 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9214930412832771328', 'uuid-lslong': '11100962947503681323'}, 'enable': 'true', 'created': '2025-10-09T03:06:33', 'last-modified': '2025-10-09T03:06:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.389839'}
2025-10-09 03:06:33,840 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:33,848 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9214930412832771328', 'uuid-lslong': '11100962947503681323'}, 'enable': 'true', 'created': '2025-10-09T03:06:33', 'last-modified': '2025-10-09T03:06:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403883'}
2025-10-09 03:06:33,853 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:33,853 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-26089291 passed
2025-10-09 03:06:33,853 - DEBUG - ====Verifying policy data for service-left-vn-26089291 in API_Server ======
2025-10-09 03:06:33,853 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:33,860 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:33,869 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:33,963 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7fe20263-3ed7-4d00-9a0e-8afc3ebaef2b
2025-10-09 03:06:33,977 - DEBUG - =>VN service-left-vn-26089291 has no policy to be verified
2025-10-09 03:06:33,977 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:33,977 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:33,977 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:34,031 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291?flat'}
2025-10-09 03:06:34,031 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:34,031 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:34,086 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291?flat'}
2025-10-09 03:06:34,086 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:34,086 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:34,136 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291?flat'}
2025-10-09 03:06:34,136 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:service-left-vn-26089291 is found in opserver
2025-10-09 03:06:34,156 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:34,164 - DEBUG - VRF ids for VN service-left-vn-26089291: {}
2025-10-09 03:06:34,428 - DEBUG - Response for create_network : {'network': {'id': '1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88', 'name': 'service-right-vn-09443453', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'service-right-vn-09443453'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:34,690 - DEBUG - Response for create_subnet : {'subnet': {'id': '09412fc7-bb99-497f-bfcb-b9e7a40194f0', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': '1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88', 'ip_version': 4, 'cidr': '20.232.228.64/26', 'allocation_pools': [{'start': '20.232.228.66', 'end': '20.232.228.126'}], 'gateway_ip': '20.232.228.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.232.228.66', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:34,720 - INFO - Created VN service-right-vn-09443453
2025-10-09 03:06:34,734 - DEBUG - VN service-right-vn-09443453 UUID is 1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:34,813 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:34,821 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:34,831 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:34,912 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:34,941 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:34,950 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:34,959 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:34,965 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/49cd4583-4ecc-465e-b704-0d741f2a5ed1
2025-10-09 03:06:34,972 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:34,972 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:34,982 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:34,997 - INFO - Verified VN network id 16 for VN 1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:34,997 - INFO - Verifications in API Server for VN service-right-vn-09443453 passed
2025-10-09 03:06:34,998 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:35,006 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:35,013 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:35,019 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/49cd4583-4ecc-465e-b704-0d741f2a5ed1
2025-10-09 03:06:35,031 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2100534014001368572', 'uuid-lslong': '11431657372980488840'}, 'enable': 'true', 'created': '2025-10-09T03:06:34', 'last-modified': '2025-10-09T03:06:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.406424'}
2025-10-09 03:06:35,036 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:35,042 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2100534014001368572', 'uuid-lslong': '11431657372980488840'}, 'enable': 'true', 'created': '2025-10-09T03:06:34', 'last-modified': '2025-10-09T03:06:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414941'}
2025-10-09 03:06:35,050 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:35,055 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2100534014001368572', 'uuid-lslong': '11431657372980488840'}, 'enable': 'true', 'created': '2025-10-09T03:06:34', 'last-modified': '2025-10-09T03:06:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.427822'}
2025-10-09 03:06:35,061 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:35,061 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-09443453 passed
2025-10-09 03:06:35,061 - DEBUG - ====Verifying policy data for service-right-vn-09443453 in API_Server ======
2025-10-09 03:06:35,061 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:35,068 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:35,082 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:35,191 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88
2025-10-09 03:06:35,200 - DEBUG - =>VN service-right-vn-09443453 has no policy to be verified
2025-10-09 03:06:35,200 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:35,200 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:35,200 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:35,252 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453?flat'}
2025-10-09 03:06:35,252 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:35,252 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:35,306 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453?flat'}
2025-10-09 03:06:35,306 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:35,306 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:35,361 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453?flat'}
2025-10-09 03:06:35,361 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:service-right-vn-09443453 is found in opserver
2025-10-09 03:06:35,383 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:35,392 - DEBUG - VRF ids for VN service-right-vn-09443453: {}
2025-10-09 03:06:35,456 - DEBUG - Services list from nova: [, , , ]
2025-10-09 03:06:36,563 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 03:06:38,003 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-1)
2025-10-09 03:06:38,003 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-76875712']
2025-10-09 03:06:38,324 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-76875712'] created succesfully
2025-10-09 03:06:38,687 - DEBUG - Image by name tiny_nat_fw not found
2025-10-09 03:06:38,687 - DEBUG - Installing image tiny_nat_fw
2025-10-09 03:06:38,687 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-09 03:06:38,687 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:38,687 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:38,687 - DEBUG - Unzip image
2025-10-09 03:06:38,687 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:38,712 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-09 03:06:38,713 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-76875712']
2025-10-09 03:06:38,848 - INFO - Service template: ['default-domain', 'ctest-service_template_1-76875712'] deleted successfully.
2025-10-09 03:06:38,850 - INFO - Skip interface_detach for VM ctest-right_vm-58280938 in state BUILD
2025-10-09 03:06:38,850 - INFO - Deleting VM ctest-right_vm-58280938
2025-10-09 03:06:38,887 - ERROR - VM ctest-right_vm-58280938 has failed to come up
2025-10-09 03:06:38,887 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:38,887 - ERROR - VM ctest-right_vm-58280938 has failed to come up
2025-10-09 03:06:38,887 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:38,995 - INFO - Skip interface_detach for VM ctest-left_vm-50326941 in state BUILD
2025-10-09 03:06:38,995 - INFO - Deleting VM ctest-left_vm-50326941
2025-10-09 03:06:38,995 - ERROR - VM ctest-left_vm-50326941 has failed to come up
2025-10-09 03:06:38,995 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:38,996 - ERROR - VM ctest-left_vm-50326941 has failed to come up
2025-10-09 03:06:38,996 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:39,114 - INFO - Deleting VN service-right-vn-09443453
2025-10-09 03:06:39,174 - DEBUG - VN 1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88 still in use: Unable to complete operation on network 1d26988e-7f2d-4dfc-9ea5-67d02e7a2a88. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-73a1a79a-19ca-4c64-8916-963e2d286ec6']
2025-10-09 03:06:39,174 - WARNING - Deleting VN service-right-vn-09443453 failed..Will retry
2025-10-09 03:06:41,325 - DEBUG - Response for deleting network ()
2025-10-09 03:06:41,326 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/7c319d4b-2afa-449f-a888-c384de7fd2ae
2025-10-09 03:06:41,333 - DEBUG - Response Code: 404
2025-10-09 03:06:41,333 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:41,355 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:41,376 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:41,486 - INFO - Validated that VN service-right-vn-09443453 is not found in API Server
2025-10-09 03:06:41,497 - DEBUG - VN service-right-vn-09443453 is not present in Agent 10.0.0.22
2025-10-09 03:06:41,514 - DEBUG - VN service-right-vn-09443453 is not present in Agent 10.0.0.27
2025-10-09 03:06:41,514 - INFO - Validated that VN service-right-vn-09443453 is not in any agent
2025-10-09 03:06:41,551 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-09443453 info
2025-10-09 03:06:41,551 - INFO - Deleting VN service-left-vn-26089291
2025-10-09 03:06:41,706 - DEBUG - Response for deleting network ()
2025-10-09 03:06:41,706 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/e654920b-635e-4938-ac8a-2bcfc0f7b0d2
2025-10-09 03:06:41,713 - DEBUG - Response Code: 404
2025-10-09 03:06:41,713 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:41,719 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:41,736 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:41,827 - INFO - Validated that VN service-left-vn-26089291 is not found in API Server
2025-10-09 03:06:41,840 - DEBUG - VN service-left-vn-26089291 is not present in Agent 10.0.0.22
2025-10-09 03:06:41,850 - DEBUG - VN service-left-vn-26089291 is not present in Agent 10.0.0.27
2025-10-09 03:06:41,850 - INFO - Validated that VN service-left-vn-26089291 is not in any agent
2025-10-09 03:06:41,880 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-26089291 info
2025-10-09 03:06:41,880 - INFO - Deleting VN ctest-mgmt_vn-70718935
2025-10-09 03:06:42,085 - DEBUG - Response for deleting network ()
2025-10-09 03:06:42,085 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/572c7bed-6d98-4102-b32e-86c6958f3430
2025-10-09 03:06:42,094 - DEBUG - Response Code: 404
2025-10-09 03:06:42,094 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:42,101 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:42,116 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:42,206 - INFO - Validated that VN ctest-mgmt_vn-70718935 is not found in API Server
2025-10-09 03:06:42,219 - DEBUG - VN ctest-mgmt_vn-70718935 is not present in Agent 10.0.0.22
2025-10-09 03:06:42,234 - DEBUG - VN ctest-mgmt_vn-70718935 is not present in Agent 10.0.0.27
2025-10-09 03:06:42,234 - INFO - Validated that VN ctest-mgmt_vn-70718935 is not in any agent
2025-10-09 03:06:42,265 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-70718935 info
2025-10-09 03:06:43,518 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 03:06:43,518 - INFO -
2025-10-09 03:06:43,518 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-10-09 03:06:43,518 - INFO - --------------------------------------------------------------------------------
2025-10-09 03:06:43,521 - INFO - ================================================================================
2025-10-09 03:06:43,521 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-10-09 03:06:44,800 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.27': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-10-09 03:06:44,800 - INFO - Initial checks done. Running the testcase now
2025-10-09 03:06:44,800 - INFO -
2025-10-09 03:06:45,066 - DEBUG - Response for create_network : {'network': {'id': 'd6a8b73d-066b-4b01-b3f3-ab98239b20fe', 'name': 'ctest-mgmt_vn-09985236', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'ctest-mgmt_vn-09985236'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:45,304 - DEBUG - Response for create_subnet : {'subnet': {'id': '4d287b33-367e-4774-b87b-c457c0471548', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': 'd6a8b73d-066b-4b01-b3f3-ab98239b20fe', 'ip_version': 4, 'cidr': '218.67.84.128/26', 'allocation_pools': [{'start': '218.67.84.130', 'end': '218.67.84.190'}], 'gateway_ip': '218.67.84.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.67.84.130', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:45,333 - INFO - Created VN ctest-mgmt_vn-09985236
2025-10-09 03:06:45,346 - DEBUG - VN ctest-mgmt_vn-09985236 UUID is d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,421 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:45,427 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:45,439 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:45,543 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,555 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,565 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:45,572 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:45,578 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/50000c53-7759-4654-a617-2c8b7ce6a75d
2025-10-09 03:06:45,585 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:45,585 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,596 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:45,610 - INFO - Verified VN network id 14 for VN d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,610 - INFO - Verifications in API Server for VN ctest-mgmt_vn-09985236 passed
2025-10-09 03:06:45,610 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,619 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:45,630 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:45,637 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/50000c53-7759-4654-a617-2c8b7ce6a75d
2025-10-09 03:06:45,650 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15467814392932551425', 'uuid-lslong': '12966896422050537726'}, 'enable': 'true', 'created': '2025-10-09T03:06:45', 'last-modified': '2025-10-09T03:06:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.410005'}
2025-10-09 03:06:45,660 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:45,667 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15467814392932551425', 'uuid-lslong': '12966896422050537726'}, 'enable': 'true', 'created': '2025-10-09T03:06:45', 'last-modified': '2025-10-09T03:06:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.428130'}
2025-10-09 03:06:45,673 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:45,682 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15467814392932551425', 'uuid-lslong': '12966896422050537726'}, 'enable': 'true', 'created': '2025-10-09T03:06:45', 'last-modified': '2025-10-09T03:06:45', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441925'}
2025-10-09 03:06:45,688 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 03:06:45,688 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-09985236 passed
2025-10-09 03:06:45,688 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-09985236 in API_Server ======
2025-10-09 03:06:45,688 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:45,695 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:45,707 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:45,809 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/d6a8b73d-066b-4b01-b3f3-ab98239b20fe
2025-10-09 03:06:45,820 - DEBUG - =>VN ctest-mgmt_vn-09985236 has no policy to be verified
2025-10-09 03:06:45,820 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:45,820 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:45,820 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:45,834 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236?flat'}
2025-10-09 03:06:45,834 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:45,834 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:45,848 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236?flat'}
2025-10-09 03:06:45,848 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:45,848 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:45,863 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236?flat'}
2025-10-09 03:06:45,863 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:ctest-mgmt_vn-09985236 is found in opserver
2025-10-09 03:06:45,882 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:45,891 - DEBUG - VRF ids for VN ctest-mgmt_vn-09985236: {}
2025-10-09 03:06:46,190 - DEBUG - Response for create_network : {'network': {'id': '7a3373ec-24a9-4c75-9b53-b608782b4da7', 'name': 'ctest-left_vn-51592376', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'ctest-left_vn-51592376'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:46,420 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea7e5b0d-5331-4464-a004-bb1083fc1bfd', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': '7a3373ec-24a9-4c75-9b53-b608782b4da7', 'ip_version': 4, 'cidr': '88.117.126.64/26', 'allocation_pools': [{'start': '88.117.126.66', 'end': '88.117.126.126'}], 'gateway_ip': '88.117.126.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.117.126.66', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:46,445 - INFO - Created VN ctest-left_vn-51592376
2025-10-09 03:06:46,457 - DEBUG - VN ctest-left_vn-51592376 UUID is 7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,528 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:46,535 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:46,545 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:46,649 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,663 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,673 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:46,682 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:46,690 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9240eeba-2d33-42ef-946b-145a4c8fca9a
2025-10-09 03:06:46,697 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:46,697 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,708 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:46,724 - INFO - Verified VN network id 15 for VN 7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,724 - INFO - Verifications in API Server for VN ctest-left_vn-51592376 passed
2025-10-09 03:06:46,724 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,734 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:46,741 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:46,748 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/9240eeba-2d33-42ef-946b-145a4c8fca9a
2025-10-09 03:06:46,760 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8805509154504002677', 'uuid-lslong': '11192489646437911975'}, 'enable': 'true', 'created': '2025-10-09T03:06:46', 'last-modified': '2025-10-09T03:06:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.397094'}
2025-10-09 03:06:46,766 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:46,771 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8805509154504002677', 'uuid-lslong': '11192489646437911975'}, 'enable': 'true', 'created': '2025-10-09T03:06:46', 'last-modified': '2025-10-09T03:06:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411444'}
2025-10-09 03:06:46,777 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:46,782 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8805509154504002677', 'uuid-lslong': '11192489646437911975'}, 'enable': 'true', 'created': '2025-10-09T03:06:46', 'last-modified': '2025-10-09T03:06:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.422587'}
2025-10-09 03:06:46,788 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 03:06:46,788 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-51592376 passed
2025-10-09 03:06:46,788 - DEBUG - ====Verifying policy data for ctest-left_vn-51592376 in API_Server ======
2025-10-09 03:06:46,788 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:46,794 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:46,805 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:46,902 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/7a3373ec-24a9-4c75-9b53-b608782b4da7
2025-10-09 03:06:46,911 - DEBUG - =>VN ctest-left_vn-51592376 has no policy to be verified
2025-10-09 03:06:46,911 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:46,911 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:46,911 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:46,923 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376?flat'}
2025-10-09 03:06:46,923 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:46,923 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:46,937 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376?flat'}
2025-10-09 03:06:46,937 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:46,937 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:46,951 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376?flat'}
2025-10-09 03:06:46,951 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:ctest-left_vn-51592376 is found in opserver
2025-10-09 03:06:46,975 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:46,984 - DEBUG - VRF ids for VN ctest-left_vn-51592376: {}
2025-10-09 03:06:47,249 - DEBUG - Response for create_network : {'network': {'id': 'f3f4f4bd-e230-45cd-82a2-6d595749692a', 'name': 'ctest-right_vn-05932223', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'project_id': '8077a444d0a945098c55b16de41335f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-19286188', 'ctest-right_vn-05932223'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 03:06:47,458 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f851554f-60b6-4e05-b77c-53ede125fc3b', 'name': '', 'tenant_id': '8077a444d0a945098c55b16de41335f7', 'network_id': 'f3f4f4bd-e230-45cd-82a2-6d595749692a', 'ip_version': 4, 'cidr': '88.199.236.0/26', 'allocation_pools': [{'start': '88.199.236.2', 'end': '88.199.236.62'}], 'gateway_ip': '88.199.236.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '88.199.236.2', 'tags': [], 'project_id': '8077a444d0a945098c55b16de41335f7'}}
2025-10-09 03:06:47,488 - INFO - Created VN ctest-right_vn-05932223
2025-10-09 03:06:47,499 - DEBUG - VN ctest-right_vn-05932223 UUID is f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,570 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:47,577 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:47,591 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:47,690 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,704 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,716 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:47,726 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:47,734 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/bd7945cc-318e-4e14-96b5-fe1ec79e5d96
2025-10-09 03:06:47,740 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:47,741 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,753 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:47,769 - INFO - Verified VN network id 16 for VN f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,769 - INFO - Verifications in API Server for VN ctest-right_vn-05932223 passed
2025-10-09 03:06:47,769 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,781 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:47,787 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:47,795 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/bd7945cc-318e-4e14-96b5-fe1ec79e5d96
2025-10-09 03:06:47,809 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17578944341914699213', 'uuid-lslong': '9413206401641703722'}, 'enable': 'true', 'created': '2025-10-09T03:06:47', 'last-modified': '2025-10-09T03:06:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387672'}
2025-10-09 03:06:47,818 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:47,823 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17578944341914699213', 'uuid-lslong': '9413206401641703722'}, 'enable': 'true', 'created': '2025-10-09T03:06:47', 'last-modified': '2025-10-09T03:06:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407635'}
2025-10-09 03:06:47,829 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:47,834 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-19286188', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17578944341914699213', 'uuid-lslong': '9413206401641703722'}, 'enable': 'true', 'created': '2025-10-09T03:06:47', 'last-modified': '2025-10-09T03:06:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.415575'}
2025-10-09 03:06:47,839 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 03:06:47,840 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-05932223 passed
2025-10-09 03:06:47,840 - DEBUG - ====Verifying policy data for ctest-right_vn-05932223 in API_Server ======
2025-10-09 03:06:47,840 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:47,846 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:47,857 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:47,954 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/f3f4f4bd-e230-45cd-82a2-6d595749692a
2025-10-09 03:06:47,964 - DEBUG - =>VN ctest-right_vn-05932223 has no policy to be verified
2025-10-09 03:06:47,964 - DEBUG - Verifying the vn in opserver
2025-10-09 03:06:47,964 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223 virtual network link through opserver 10.0.0.56
2025-10-09 03:06:47,964 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 03:06:47,975 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223?flat'}
2025-10-09 03:06:47,975 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223 virtual network link through opserver 10.0.0.19
2025-10-09 03:06:47,975 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 03:06:47,990 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223?flat'}
2025-10-09 03:06:47,990 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223 virtual network link through opserver 10.0.0.41
2025-10-09 03:06:47,990 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 03:06:48,003 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223?flat'}
2025-10-09 03:06:48,003 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-19286188:ctest-right_vn-05932223 is found in opserver
2025-10-09 03:06:48,021 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 03:06:48,032 - DEBUG - VRF ids for VN ctest-right_vn-05932223: {}
2025-10-09 03:06:49,095 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-1)
2025-10-09 03:06:50,433 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 03:06:50,433 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-35860179']
2025-10-09 03:06:50,724 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-35860179'] created succesfully
2025-10-09 03:06:51,074 - DEBUG - Image by name tiny_nat_fw not found
2025-10-09 03:06:51,074 - DEBUG - Installing image tiny_nat_fw
2025-10-09 03:06:51,074 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-10-09 03:06:51,075 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:51,075 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:51,075 - DEBUG - Unzip image
2025-10-09 03:06:51,075 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-10-09 03:06:51,094 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-10-09 03:06:51,094 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-35860179']
2025-10-09 03:06:51,209 - INFO - Service template: ['default-domain', 'ctest-service_template_1-35860179'] deleted successfully.
2025-10-09 03:06:51,210 - INFO - Skip interface_detach for VM ctest-right_vm-88063438 in state BUILD
2025-10-09 03:06:51,210 - INFO - Deleting VM ctest-right_vm-88063438
2025-10-09 03:06:51,210 - ERROR - VM ctest-right_vm-88063438 has failed to come up
2025-10-09 03:06:51,210 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:51,210 - ERROR - VM ctest-right_vm-88063438 has failed to come up
2025-10-09 03:06:51,210 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:51,315 - INFO - Skip interface_detach for VM ctest-left_vm-41476153 in state BUILD
2025-10-09 03:06:51,315 - INFO - Deleting VM ctest-left_vm-41476153
2025-10-09 03:06:51,315 - ERROR - VM ctest-left_vm-41476153 has failed to come up
2025-10-09 03:06:51,315 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:51,315 - ERROR - VM ctest-left_vm-41476153 has failed to come up
2025-10-09 03:06:51,315 - ERROR - Nova failed to get host of the VM
2025-10-09 03:06:51,405 - INFO - Deleting VN ctest-right_vn-05932223
2025-10-09 03:06:51,459 - DEBUG - VN f3f4f4bd-e230-45cd-82a2-6d595749692a still in use: Unable to complete operation on network f3f4f4bd-e230-45cd-82a2-6d595749692a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-7446dc26-0af7-4b68-bb94-fb8aa80364fb']
2025-10-09 03:06:51,459 - WARNING - Deleting VN ctest-right_vn-05932223 failed..Will retry
2025-10-09 03:06:53,620 - DEBUG - Response for deleting network ()
2025-10-09 03:06:53,620 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/a2beae42-ca4d-4798-b556-93d97a439741
2025-10-09 03:06:53,626 - DEBUG - Response Code: 404
2025-10-09 03:06:53,626 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:53,637 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:53,694 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:53,807 - INFO - Validated that VN ctest-right_vn-05932223 is not found in API Server
2025-10-09 03:06:53,817 - DEBUG - VN ctest-right_vn-05932223 is not present in Agent 10.0.0.22
2025-10-09 03:06:53,827 - DEBUG - VN ctest-right_vn-05932223 is not present in Agent 10.0.0.27
2025-10-09 03:06:53,827 - INFO - Validated that VN ctest-right_vn-05932223 is not in any agent
2025-10-09 03:06:53,858 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-05932223 info
2025-10-09 03:06:53,858 - INFO - Deleting VN ctest-left_vn-51592376
2025-10-09 03:06:54,036 - DEBUG - Response for deleting network ()
2025-10-09 03:06:54,036 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8edf750a-f3f7-4b92-ac59-09eb8116c1aa
2025-10-09 03:06:54,046 - DEBUG - Response Code: 404
2025-10-09 03:06:54,047 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:54,052 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:54,107 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:54,206 - INFO - Validated that VN ctest-left_vn-51592376 is not found in API Server
2025-10-09 03:06:54,217 - DEBUG - VN ctest-left_vn-51592376 is not present in Agent 10.0.0.22
2025-10-09 03:06:54,228 - DEBUG - VN ctest-left_vn-51592376 is not present in Agent 10.0.0.27
2025-10-09 03:06:54,228 - INFO - Validated that VN ctest-left_vn-51592376 is not in any agent
2025-10-09 03:06:54,255 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-51592376 info
2025-10-09 03:06:54,256 - INFO - Deleting VN ctest-mgmt_vn-09985236
2025-10-09 03:06:54,405 - DEBUG - Response for deleting network ()
2025-10-09 03:06:54,405 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/58e34fa7-6e15-4176-b1e8-695637e9d0bb
2025-10-09 03:06:54,411 - DEBUG - Response Code: 404
2025-10-09 03:06:54,411 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 03:06:54,418 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 03:06:54,442 - DEBUG - Requesting: http://10.0.0.56:8082/project/8077a444-d0a9-4509-8c55-b16de41335f7
2025-10-09 03:06:54,532 - INFO - Validated that VN ctest-mgmt_vn-09985236 is not found in API Server
2025-10-09 03:06:54,541 - DEBUG - VN ctest-mgmt_vn-09985236 is not present in Agent 10.0.0.22
2025-10-09 03:06:54,553 - DEBUG - VN ctest-mgmt_vn-09985236 is not present in Agent 10.0.0.27
2025-10-09 03:06:54,553 - INFO - Validated that VN ctest-mgmt_vn-09985236 is not in any agent
2025-10-09 03:06:54,583 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-09985236 info
2025-10-09 03:06:55,854 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 03:06:55,854 - INFO -
2025-10-09 03:06:55,855 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-10-09 03:06:55,855 - INFO - --------------------------------------------------------------------------------
2025-10-09 03:06:56,590 - INFO - Deleted project: ctest-TestSvcRegr-19286188, ID : 8077a444-d0a9-4509-8c55-b16de41335f7