]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:01:50,055 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-56362754']
2025-08-11 07:01:50,349 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-56362754'] created succesfully
2025-08-11 07:01:50,743 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 07:01:50,744 - DEBUG - Installing image tiny_nat_fw
2025-08-11 07:01:50,744 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 07:01:50,744 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-11 07:01:50,744 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz
2025-08-11 07:01:50,758 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-in-network-nat.qcow2.gz -O /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:01:50,805 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:01:50,805 - DEBUG - Unzip image
2025-08-11 07:01:50,805 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:01:50,846 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 07:01:50,846 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-56362754']
2025-08-11 07:01:50,944 - INFO - Service template: ['default-domain', 'ctest-service_template_1-56362754'] deleted successfully.
2025-08-11 07:01:50,945 - INFO - Skip interface_detach for VM ctest-right_vm-75327405 in state BUILD
2025-08-11 07:01:50,945 - INFO - Deleting VM ctest-right_vm-75327405
2025-08-11 07:01:50,969 - ERROR - VM ctest-right_vm-75327405 has failed to come up
2025-08-11 07:01:50,969 - ERROR - Nova failed to get host of the VM
2025-08-11 07:01:50,969 - ERROR - VM ctest-right_vm-75327405 has failed to come up
2025-08-11 07:01:50,970 - ERROR - Nova failed to get host of the VM
2025-08-11 07:01:51,026 - INFO - Skip interface_detach for VM ctest-left_vm-16129520 in state BUILD
2025-08-11 07:01:51,027 - INFO - Deleting VM ctest-left_vm-16129520
2025-08-11 07:01:51,027 - ERROR - VM ctest-left_vm-16129520 has failed to come up
2025-08-11 07:01:51,027 - ERROR - Nova failed to get host of the VM
2025-08-11 07:01:51,027 - ERROR - VM ctest-left_vm-16129520 has failed to come up
2025-08-11 07:01:51,028 - ERROR - Nova failed to get host of the VM
2025-08-11 07:01:51,092 - INFO - Deleting VN ctest-right_vn-75812393
2025-08-11 07:01:51,127 - DEBUG - VN 0dd44467-ce68-4af0-bba1-6c03de62c782 still in use: Unable to complete operation on network 0dd44467-ce68-4af0-bba1-6c03de62c782. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e0ed68f1-8105-4105-b2c2-eba6e3c3ecfd']
2025-08-11 07:01:51,127 - WARNING - Deleting VN ctest-right_vn-75812393 failed..Will retry
2025-08-11 07:01:53,340 - DEBUG - Response for deleting network ()
2025-08-11 07:01:53,340 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/607fe44f-6744-46c2-9452-08f825bc4ae4
2025-08-11 07:01:53,347 - DEBUG - Response Code: 404
2025-08-11 07:01:53,347 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:01:53,355 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:01:53,413 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:01:53,500 - INFO - Validated that VN ctest-right_vn-75812393 is not found in API Server
2025-08-11 07:01:53,512 - DEBUG - VN ctest-right_vn-75812393 is not present in Agent 10.0.0.49
2025-08-11 07:01:53,523 - DEBUG - VN ctest-right_vn-75812393 is not present in Agent 10.0.0.57
2025-08-11 07:01:53,523 - INFO - Validated that VN ctest-right_vn-75812393 is not in any agent
2025-08-11 07:01:53,553 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-75812393 info
2025-08-11 07:01:53,554 - INFO - Deleting VN ctest-left_vn-29740804
2025-08-11 07:01:53,692 - DEBUG - Response for deleting network ()
2025-08-11 07:01:53,693 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/a556bb14-9dcf-44d3-ac54-44b79fd7b2d0
2025-08-11 07:01:53,699 - DEBUG - Response Code: 404
2025-08-11 07:01:53,699 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:01:53,711 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:01:53,727 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:01:53,816 - INFO - Validated that VN ctest-left_vn-29740804 is not found in API Server
2025-08-11 07:01:53,828 - DEBUG - VN ctest-left_vn-29740804 is not present in Agent 10.0.0.49
2025-08-11 07:01:53,842 - DEBUG - VN ctest-left_vn-29740804 is not present in Agent 10.0.0.57
2025-08-11 07:01:53,842 - INFO - Validated that VN ctest-left_vn-29740804 is not in any agent
2025-08-11 07:01:53,877 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-29740804 info
2025-08-11 07:01:53,878 - INFO - Deleting VN ctest-mgmt_vn-53420680
2025-08-11 07:01:54,013 - DEBUG - Response for deleting network ()
2025-08-11 07:01:54,013 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/463b5131-a70e-475c-96a6-399231fcdae5
2025-08-11 07:01:54,019 - DEBUG - Response Code: 404
2025-08-11 07:01:54,019 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:01:54,025 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:01:54,050 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:01:54,148 - INFO - Validated that VN ctest-mgmt_vn-53420680 is not found in API Server
2025-08-11 07:01:54,159 - DEBUG - VN ctest-mgmt_vn-53420680 is not present in Agent 10.0.0.49
2025-08-11 07:01:54,173 - DEBUG - VN ctest-mgmt_vn-53420680 is not present in Agent 10.0.0.57
2025-08-11 07:01:54,173 - INFO - Validated that VN ctest-mgmt_vn-53420680 is not in any agent
2025-08-11 07:01:54,208 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-53420680 info
2025-08-11 07:01:54,208 - INFO - Deleting VM ctest-vm-in-third-vn-50911173
2025-08-11 07:01:54,293 - INFO - Deleting VN ctest-third-vn-51570466
2025-08-11 07:01:54,332 - DEBUG - VN a42d5035-090e-4e70-850c-8d344a19bf79 still in use: Unable to complete operation on network a42d5035-090e-4e70-850c-8d344a19bf79. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b5672d78-b379-4c1d-abcb-2f7959e2f5f9']
2025-08-11 07:01:54,332 - WARNING - Deleting VN ctest-third-vn-51570466 failed..Will retry
2025-08-11 07:01:56,478 - DEBUG - Response for deleting network ()
2025-08-11 07:01:57,739 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 07:01:57,739 - INFO -
2025-08-11 07:01:57,739 - INFO - END TEST : test_svc_in_net_nat_with_static_routes : SKIPPED[0:00:23]
2025-08-11 07:01:57,739 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:01:57,742 - INFO - ================================================================================
2025-08-11 07:01:57,742 - INFO - STARTING TEST : test_svc_v2_transparent_datapath
2025-08-11 07:01:58,993 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.57': {'10.20.0.17': '0', '10.20.0.254': '0'}} with
2025-08-11 07:01:58,993 - INFO - Initial checks done. Running the testcase now
2025-08-11 07:01:58,994 - INFO -
2025-08-11 07:01:59,277 - DEBUG - Response for create_network : {'network': {'id': '007caac2-00c6-45c9-8ac8-c3a8f40ad2fc', 'name': 'ctest-mgmt_vn-83983266', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'project_id': 'd453d6d1094e435595c65b203fd1f8c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30401650', 'ctest-mgmt_vn-83983266'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:01:59,488 - DEBUG - Response for create_subnet : {'subnet': {'id': '60cda931-0179-40e4-b52c-40765cf71f64', 'name': '', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'network_id': '007caac2-00c6-45c9-8ac8-c3a8f40ad2fc', 'ip_version': 4, 'cidr': '170.215.139.64/26', 'allocation_pools': [{'start': '170.215.139.66', 'end': '170.215.139.126'}], 'gateway_ip': '170.215.139.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '170.215.139.66', 'tags': [], 'project_id': 'd453d6d1094e435595c65b203fd1f8c5'}}
2025-08-11 07:01:59,524 - INFO - Created VN ctest-mgmt_vn-83983266
2025-08-11 07:01:59,535 - DEBUG - VN ctest-mgmt_vn-83983266 UUID is 007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,610 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:01:59,617 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:01:59,630 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:01:59,724 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,736 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,746 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:01:59,755 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:01:59,763 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/611ff464-92c9-4187-9ad5-5211eaec04a3
2025-08-11 07:01:59,770 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:01:59,770 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,779 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:01:59,794 - INFO - Verified VN network id 14 for VN 007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,795 - INFO - Verifications in API Server for VN ctest-mgmt_vn-83983266 passed
2025-08-11 07:01:59,795 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,804 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:01:59,811 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:01:59,819 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/611ff464-92c9-4187-9ad5-5211eaec04a3
2025-08-11 07:01:59,831 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '35090647325492681', 'uuid-lslong': '10000458102992458492'}, 'enable': 'true', 'created': '2025-08-11T07:01:59', 'last-modified': '2025-08-11T07:01:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393958'}
2025-08-11 07:01:59,838 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:01:59,844 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '35090647325492681', 'uuid-lslong': '10000458102992458492'}, 'enable': 'true', 'created': '2025-08-11T07:01:59', 'last-modified': '2025-08-11T07:01:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407009'}
2025-08-11 07:01:59,850 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:01:59,856 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '35090647325492681', 'uuid-lslong': '10000458102992458492'}, 'enable': 'true', 'created': '2025-08-11T07:01:59', 'last-modified': '2025-08-11T07:01:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.420802'}
2025-08-11 07:01:59,863 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:01:59,863 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-83983266 passed
2025-08-11 07:01:59,863 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-83983266 in API_Server ======
2025-08-11 07:01:59,863 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:01:59,870 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:01:59,883 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:01:59,977 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/007caac2-00c6-45c9-8ac8-c3a8f40ad2fc
2025-08-11 07:01:59,987 - DEBUG - =>VN ctest-mgmt_vn-83983266 has no policy to be verified
2025-08-11 07:01:59,987 - DEBUG - Verifying the vn in opserver
2025-08-11 07:01:59,987 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266 virtual network link through opserver 10.0.0.254
2025-08-11 07:01:59,987 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:01:59,999 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266?flat'}
2025-08-11 07:01:59,999 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266 virtual network link through opserver 10.0.0.38
2025-08-11 07:01:59,999 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:00,010 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266?flat'}
2025-08-11 07:02:00,011 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:00,011 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:00,023 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266?flat'}
2025-08-11 07:02:00,023 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30401650:ctest-mgmt_vn-83983266 is found in opserver
2025-08-11 07:02:00,046 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:00,056 - DEBUG - VRF ids for VN ctest-mgmt_vn-83983266: {}
2025-08-11 07:02:00,307 - DEBUG - Response for create_network : {'network': {'id': '68b5afd6-6429-481a-acaf-0b96a263e426', 'name': 'ctest-left_vn-40757389', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'project_id': 'd453d6d1094e435595c65b203fd1f8c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30401650', 'ctest-left_vn-40757389'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:00,559 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d508179-aac0-4def-99f4-de39fd8f3a84', 'name': '', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'network_id': '68b5afd6-6429-481a-acaf-0b96a263e426', 'ip_version': 4, 'cidr': '33.39.21.192/26', 'allocation_pools': [{'start': '33.39.21.194', 'end': '33.39.21.254'}], 'gateway_ip': '33.39.21.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '33.39.21.194', 'tags': [], 'project_id': 'd453d6d1094e435595c65b203fd1f8c5'}}
2025-08-11 07:02:00,580 - INFO - Created VN ctest-left_vn-40757389
2025-08-11 07:02:00,595 - DEBUG - VN ctest-left_vn-40757389 UUID is 68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,660 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:00,667 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:00,679 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:00,777 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,798 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,807 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:00,815 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:00,822 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/79da646f-1d10-4e4a-af56-e3832e9556c9
2025-08-11 07:02:00,829 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:00,829 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,840 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:00,855 - INFO - Verified VN network id 15 for VN 68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,855 - INFO - Verifications in API Server for VN ctest-left_vn-40757389 passed
2025-08-11 07:02:00,855 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:00,867 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:00,875 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:00,883 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/79da646f-1d10-4e4a-af56-e3832e9556c9
2025-08-11 07:02:00,898 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7545130086067423258', 'uuid-lslong': '12443177037045425190'}, 'enable': 'true', 'created': '2025-08-11T07:02:00', 'last-modified': '2025-08-11T07:02:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.387003'}
2025-08-11 07:02:00,905 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:00,912 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7545130086067423258', 'uuid-lslong': '12443177037045425190'}, 'enable': 'true', 'created': '2025-08-11T07:02:00', 'last-modified': '2025-08-11T07:02:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405607'}
2025-08-11 07:02:00,918 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:00,923 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7545130086067423258', 'uuid-lslong': '12443177037045425190'}, 'enable': 'true', 'created': '2025-08-11T07:02:00', 'last-modified': '2025-08-11T07:02:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418112'}
2025-08-11 07:02:00,938 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:00,938 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-40757389 passed
2025-08-11 07:02:00,938 - DEBUG - ====Verifying policy data for ctest-left_vn-40757389 in API_Server ======
2025-08-11 07:02:00,938 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:00,945 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:00,957 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:01,052 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/68b5afd6-6429-481a-acaf-0b96a263e426
2025-08-11 07:02:01,064 - DEBUG - =>VN ctest-left_vn-40757389 has no policy to be verified
2025-08-11 07:02:01,064 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:01,064 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:01,064 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:01,078 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389?flat'}
2025-08-11 07:02:01,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:01,078 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:01,093 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389?flat'}
2025-08-11 07:02:01,093 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:01,093 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:01,105 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389?flat'}
2025-08-11 07:02:01,105 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30401650:ctest-left_vn-40757389 is found in opserver
2025-08-11 07:02:01,128 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:01,139 - DEBUG - VRF ids for VN ctest-left_vn-40757389: {}
2025-08-11 07:02:01,419 - DEBUG - Response for create_network : {'network': {'id': '8a739358-8454-44a8-acd5-496f8ab095eb', 'name': 'ctest-right_vn-76449581', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'project_id': 'd453d6d1094e435595c65b203fd1f8c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30401650', 'ctest-right_vn-76449581'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:01,642 - DEBUG - Response for create_subnet : {'subnet': {'id': '7673ef8c-98cd-40d2-916c-a8744b1e3a88', 'name': '', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'network_id': '8a739358-8454-44a8-acd5-496f8ab095eb', 'ip_version': 4, 'cidr': '146.57.152.64/26', 'allocation_pools': [{'start': '146.57.152.66', 'end': '146.57.152.126'}], 'gateway_ip': '146.57.152.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.57.152.66', 'tags': [], 'project_id': 'd453d6d1094e435595c65b203fd1f8c5'}}
2025-08-11 07:02:01,666 - INFO - Created VN ctest-right_vn-76449581
2025-08-11 07:02:01,677 - DEBUG - VN ctest-right_vn-76449581 UUID is 8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,755 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:01,761 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:01,776 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:01,871 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,891 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,908 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:01,921 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:01,929 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/522635de-4ef5-4343-8395-ee11babbf048
2025-08-11 07:02:01,938 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:01,938 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,948 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:01,965 - INFO - Verified VN network id 16 for VN 8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,965 - INFO - Verifications in API Server for VN ctest-right_vn-76449581 passed
2025-08-11 07:02:01,965 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:01,977 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:01,987 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:01,995 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/522635de-4ef5-4343-8395-ee11babbf048
2025-08-11 07:02:02,010 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9976479607942300840', 'uuid-lslong': '12453941087980000747'}, 'enable': 'true', 'created': '2025-08-11T07:02:01', 'last-modified': '2025-08-11T07:02:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430890'}
2025-08-11 07:02:02,017 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:02,023 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9976479607942300840', 'uuid-lslong': '12453941087980000747'}, 'enable': 'true', 'created': '2025-08-11T07:02:01', 'last-modified': '2025-08-11T07:02:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440293'}
2025-08-11 07:02:02,030 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:02,035 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9976479607942300840', 'uuid-lslong': '12453941087980000747'}, 'enable': 'true', 'created': '2025-08-11T07:02:01', 'last-modified': '2025-08-11T07:02:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458508'}
2025-08-11 07:02:02,041 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:02,041 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-76449581 passed
2025-08-11 07:02:02,042 - DEBUG - ====Verifying policy data for ctest-right_vn-76449581 in API_Server ======
2025-08-11 07:02:02,042 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:02,048 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:02,061 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:02,159 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/8a739358-8454-44a8-acd5-496f8ab095eb
2025-08-11 07:02:02,171 - DEBUG - =>VN ctest-right_vn-76449581 has no policy to be verified
2025-08-11 07:02:02,171 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:02,171 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:02,171 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:02,183 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581?flat'}
2025-08-11 07:02:02,183 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:02,183 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:02,196 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581?flat'}
2025-08-11 07:02:02,196 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:02,196 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:02,208 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581?flat'}
2025-08-11 07:02:02,208 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30401650:ctest-right_vn-76449581 is found in opserver
2025-08-11 07:02:02,233 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:02,244 - DEBUG - VRF ids for VN ctest-right_vn-76449581: {}
2025-08-11 07:02:02,509 - DEBUG - Response for create_network : {'network': {'id': 'ac0a04b8-ef7f-4028-a175-6913af6335b4', 'name': 'ctest-trans_left_vn-31470354', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'project_id': 'd453d6d1094e435595c65b203fd1f8c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30401650', 'ctest-trans_left_vn-31470354'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:02,745 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c983dce2-6a98-4732-93cb-f3f91d2cfc4f', 'name': '', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'network_id': 'ac0a04b8-ef7f-4028-a175-6913af6335b4', 'ip_version': 4, 'cidr': '173.78.21.192/26', 'allocation_pools': [{'start': '173.78.21.194', 'end': '173.78.21.254'}], 'gateway_ip': '173.78.21.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.78.21.194', 'tags': [], 'project_id': 'd453d6d1094e435595c65b203fd1f8c5'}}
2025-08-11 07:02:02,765 - INFO - Created VN ctest-trans_left_vn-31470354
2025-08-11 07:02:02,776 - DEBUG - VN ctest-trans_left_vn-31470354 UUID is ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:02,854 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:02,863 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:02,880 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:02,983 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:02,994 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:03,005 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:03,017 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:03,028 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/bd027775-bf98-4e68-bb6c-d31cd7a33400
2025-08-11 07:02:03,038 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 07:02:03,038 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:03,048 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:03,064 - INFO - Verified VN network id 17 for VN ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:03,064 - INFO - Verifications in API Server for VN ctest-trans_left_vn-31470354 passed
2025-08-11 07:02:03,066 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:03,079 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:03,087 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:03,098 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/bd027775-bf98-4e68-bb6c-d31cd7a33400
2025-08-11 07:02:03,117 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12396726116629299240', 'uuid-lslong': '11634320745649354164'}, 'enable': 'true', 'created': '2025-08-11T07:02:02', 'last-modified': '2025-08-11T07:02:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.424039'}
2025-08-11 07:02:03,124 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 07:02:03,131 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12396726116629299240', 'uuid-lslong': '11634320745649354164'}, 'enable': 'true', 'created': '2025-08-11T07:02:02', 'last-modified': '2025-08-11T07:02:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433782'}
2025-08-11 07:02:03,138 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 07:02:03,143 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12396726116629299240', 'uuid-lslong': '11634320745649354164'}, 'enable': 'true', 'created': '2025-08-11T07:02:02', 'last-modified': '2025-08-11T07:02:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.441959'}
2025-08-11 07:02:03,150 - DEBUG - Route Targets: ['target:64512:8000008']
2025-08-11 07:02:03,150 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-31470354 passed
2025-08-11 07:02:03,150 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-31470354 in API_Server ======
2025-08-11 07:02:03,151 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:03,158 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:03,173 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:03,273 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/ac0a04b8-ef7f-4028-a175-6913af6335b4
2025-08-11 07:02:03,291 - DEBUG - =>VN ctest-trans_left_vn-31470354 has no policy to be verified
2025-08-11 07:02:03,291 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:03,291 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:03,291 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:03,305 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354?flat'}
2025-08-11 07:02:03,305 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:03,305 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:03,319 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354?flat'}
2025-08-11 07:02:03,319 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:03,319 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:03,332 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354?flat'}
2025-08-11 07:02:03,332 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30401650:ctest-trans_left_vn-31470354 is found in opserver
2025-08-11 07:02:03,357 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:03,368 - DEBUG - VRF ids for VN ctest-trans_left_vn-31470354: {}
2025-08-11 07:02:03,626 - DEBUG - Response for create_network : {'network': {'id': '1764b62a-da12-49e1-871c-181255d6fa04', 'name': 'ctest-trans_right_vn-56906049', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'project_id': 'd453d6d1094e435595c65b203fd1f8c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-30401650', 'ctest-trans_right_vn-56906049'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:03,910 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca955869-7b8f-4754-931e-7e38ff11903e', 'name': '', 'tenant_id': 'd453d6d1094e435595c65b203fd1f8c5', 'network_id': '1764b62a-da12-49e1-871c-181255d6fa04', 'ip_version': 4, 'cidr': '217.19.171.64/26', 'allocation_pools': [{'start': '217.19.171.66', 'end': '217.19.171.126'}], 'gateway_ip': '217.19.171.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '217.19.171.66', 'tags': [], 'project_id': 'd453d6d1094e435595c65b203fd1f8c5'}}
2025-08-11 07:02:03,943 - INFO - Created VN ctest-trans_right_vn-56906049
2025-08-11 07:02:03,956 - DEBUG - VN ctest-trans_right_vn-56906049 UUID is 1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,023 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:04,030 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:04,043 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:04,144 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,161 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,172 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:04,181 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:04,189 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/6fe9811b-1405-4161-afbf-91f41104a108
2025-08-11 07:02:04,197 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 07:02:04,198 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,209 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:04,226 - INFO - Verified VN network id 18 for VN 1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,226 - INFO - Verifications in API Server for VN ctest-trans_right_vn-56906049 passed
2025-08-11 07:02:04,226 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,237 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:04,246 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:04,255 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/6fe9811b-1405-4161-afbf-91f41104a108
2025-08-11 07:02:04,271 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1685672455706921441', 'uuid-lslong': '9735682961496799748'}, 'enable': 'true', 'created': '2025-08-11T07:02:03', 'last-modified': '2025-08-11T07:02:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.433066'}
2025-08-11 07:02:04,281 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 07:02:04,289 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1685672455706921441', 'uuid-lslong': '9735682961496799748'}, 'enable': 'true', 'created': '2025-08-11T07:02:03', 'last-modified': '2025-08-11T07:02:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.450457'}
2025-08-11 07:02:04,297 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 07:02:04,303 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-30401650', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1685672455706921441', 'uuid-lslong': '9735682961496799748'}, 'enable': 'true', 'created': '2025-08-11T07:02:03', 'last-modified': '2025-08-11T07:02:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470897'}
2025-08-11 07:02:04,310 - DEBUG - Route Targets: ['target:64512:8000009']
2025-08-11 07:02:04,310 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-56906049 passed
2025-08-11 07:02:04,310 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-56906049 in API_Server ======
2025-08-11 07:02:04,310 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:04,317 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:04,329 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:04,440 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/1764b62a-da12-49e1-871c-181255d6fa04
2025-08-11 07:02:04,453 - DEBUG - =>VN ctest-trans_right_vn-56906049 has no policy to be verified
2025-08-11 07:02:04,453 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:04,453 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:04,453 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:04,467 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049?flat'}
2025-08-11 07:02:04,467 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:04,467 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:04,487 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049?flat'}
2025-08-11 07:02:04,487 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:04,487 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:04,502 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049?flat'}
2025-08-11 07:02:04,502 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-30401650:ctest-trans_right_vn-56906049 is found in opserver
2025-08-11 07:02:04,529 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:04,539 - DEBUG - VRF ids for VN ctest-trans_right_vn-56906049: {}
2025-08-11 07:02:05,573 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:02:07,025 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1)
2025-08-11 07:02:07,026 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-15026954']
2025-08-11 07:02:07,349 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-15026954'] created succesfully
2025-08-11 07:02:07,727 - DEBUG - Image by name tiny_trans_fw not found
2025-08-11 07:02:07,728 - DEBUG - Installing image tiny_trans_fw
2025-08-11 07:02:07,728 - DEBUG - Image info {'name': 'tinycore-net-transparent-fw.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd'}
2025-08-11 07:02:07,728 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-11 07:02:07,728 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz
2025-08-11 07:02:07,752 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/tinycore//tinycore-net-transparent-fw.qcow2.gz -O /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 07:02:07,780 - DEBUG - Image has been downloaded to /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 07:02:07,780 - DEBUG - Unzip image
2025-08-11 07:02:07,780 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-net-transparent-fw.qcow2.gz
2025-08-11 07:02:07,796 - INFO - image /contrail-test/images/tinycore-net-transparent-fw.qcow2 is not found
2025-08-11 07:02:07,796 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-15026954']
2025-08-11 07:02:07,901 - INFO - Service template: ['default-domain', 'ctest-service_template_1-15026954'] deleted successfully.
2025-08-11 07:02:07,901 - INFO - Skip interface_detach for VM ctest-right_vm-59755125 in state BUILD
2025-08-11 07:02:07,901 - INFO - Deleting VM ctest-right_vm-59755125
2025-08-11 07:02:07,901 - ERROR - VM ctest-right_vm-59755125 has failed to come up
2025-08-11 07:02:07,901 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:07,901 - ERROR - VM ctest-right_vm-59755125 has failed to come up
2025-08-11 07:02:07,901 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:07,986 - INFO - Skip interface_detach for VM ctest-left_vm-72556520 in state BUILD
2025-08-11 07:02:07,986 - INFO - Deleting VM ctest-left_vm-72556520
2025-08-11 07:02:07,986 - ERROR - VM ctest-left_vm-72556520 has failed to come up
2025-08-11 07:02:07,986 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:07,987 - ERROR - VM ctest-left_vm-72556520 has failed to come up
2025-08-11 07:02:07,987 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:08,077 - INFO - Deleting VN ctest-trans_right_vn-56906049
2025-08-11 07:02:08,251 - DEBUG - Response for deleting network ()
2025-08-11 07:02:08,252 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/8cd64b2b-1f7a-4020-aa0e-a9accdfcabc5
2025-08-11 07:02:08,259 - DEBUG - Response Code: 404
2025-08-11 07:02:08,260 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:08,270 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:08,332 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:08,452 - INFO - Validated that VN ctest-trans_right_vn-56906049 is not found in API Server
2025-08-11 07:02:08,471 - DEBUG - VN ctest-trans_right_vn-56906049 is not present in Agent 10.0.0.49
2025-08-11 07:02:08,493 - DEBUG - VN ctest-trans_right_vn-56906049 is not present in Agent 10.0.0.57
2025-08-11 07:02:08,493 - INFO - Validated that VN ctest-trans_right_vn-56906049 is not in any agent
2025-08-11 07:02:08,551 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-56906049 info
2025-08-11 07:02:08,551 - INFO - Deleting VN ctest-trans_left_vn-31470354
2025-08-11 07:02:08,739 - DEBUG - Response for deleting network ()
2025-08-11 07:02:08,739 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/b2579587-540e-4b6b-b914-283c69abecb0
2025-08-11 07:02:08,749 - DEBUG - Response Code: 404
2025-08-11 07:02:08,749 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:08,769 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:08,792 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:08,906 - INFO - Validated that VN ctest-trans_left_vn-31470354 is not found in API Server
2025-08-11 07:02:08,927 - DEBUG - VN ctest-trans_left_vn-31470354 is not present in Agent 10.0.0.49
2025-08-11 07:02:08,949 - DEBUG - VN ctest-trans_left_vn-31470354 is not present in Agent 10.0.0.57
2025-08-11 07:02:08,949 - INFO - Validated that VN ctest-trans_left_vn-31470354 is not in any agent
2025-08-11 07:02:09,006 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-31470354 info
2025-08-11 07:02:09,006 - INFO - Deleting VN ctest-right_vn-76449581
2025-08-11 07:02:09,059 - DEBUG - VN 8a739358-8454-44a8-acd5-496f8ab095eb still in use: Unable to complete operation on network 8a739358-8454-44a8-acd5-496f8ab095eb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6f2e551a-36a2-4179-b2b3-eb0418ed8bd7']
2025-08-11 07:02:09,059 - WARNING - Deleting VN ctest-right_vn-76449581 failed..Will retry
2025-08-11 07:02:11,245 - DEBUG - Response for deleting network ()
2025-08-11 07:02:11,245 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/c24426dd-104f-4e5b-a79a-c93221c7eb18
2025-08-11 07:02:11,253 - DEBUG - Response Code: 404
2025-08-11 07:02:11,253 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:11,282 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:11,296 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:11,405 - INFO - Validated that VN ctest-right_vn-76449581 is not found in API Server
2025-08-11 07:02:11,422 - DEBUG - VN ctest-right_vn-76449581 is not present in Agent 10.0.0.49
2025-08-11 07:02:11,436 - DEBUG - VN ctest-right_vn-76449581 is not present in Agent 10.0.0.57
2025-08-11 07:02:11,436 - INFO - Validated that VN ctest-right_vn-76449581 is not in any agent
2025-08-11 07:02:11,474 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-76449581 info
2025-08-11 07:02:11,475 - INFO - Deleting VN ctest-left_vn-40757389
2025-08-11 07:02:11,618 - DEBUG - Response for deleting network ()
2025-08-11 07:02:11,618 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/6fd87221-9d37-487c-b2fe-8ccd44266ce1
2025-08-11 07:02:11,624 - DEBUG - Response Code: 404
2025-08-11 07:02:11,624 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:11,630 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:11,642 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:11,743 - INFO - Validated that VN ctest-left_vn-40757389 is not found in API Server
2025-08-11 07:02:11,759 - DEBUG - VN ctest-left_vn-40757389 is not present in Agent 10.0.0.49
2025-08-11 07:02:11,775 - DEBUG - VN ctest-left_vn-40757389 is not present in Agent 10.0.0.57
2025-08-11 07:02:11,775 - INFO - Validated that VN ctest-left_vn-40757389 is not in any agent
2025-08-11 07:02:11,819 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-40757389 info
2025-08-11 07:02:11,819 - INFO - Deleting VN ctest-mgmt_vn-83983266
2025-08-11 07:02:11,986 - DEBUG - Response for deleting network ()
2025-08-11 07:02:11,986 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/fd9a802e-b494-4e47-84ae-1fca880d7c6b
2025-08-11 07:02:11,996 - DEBUG - Response Code: 404
2025-08-11 07:02:11,996 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:12,006 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:12,021 - DEBUG - Requesting: http://10.0.0.254:8082/project/d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:12,144 - INFO - Validated that VN ctest-mgmt_vn-83983266 is not found in API Server
2025-08-11 07:02:12,159 - DEBUG - VN ctest-mgmt_vn-83983266 is not present in Agent 10.0.0.49
2025-08-11 07:02:12,174 - DEBUG - VN ctest-mgmt_vn-83983266 is not present in Agent 10.0.0.57
2025-08-11 07:02:12,174 - INFO - Validated that VN ctest-mgmt_vn-83983266 is not in any agent
2025-08-11 07:02:12,209 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-83983266 info
2025-08-11 07:02:13,482 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 07:02:13,482 - INFO -
2025-08-11 07:02:13,482 - INFO - END TEST : test_svc_v2_transparent_datapath : SKIPPED[0:00:16]
2025-08-11 07:02:13,482 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:02:14,213 - INFO - Deleted project: ctest-TestSvcRegr-30401650, ID : d453d6d1-094e-4355-95c6-5b203fd1f8c5
2025-08-11 07:02:14,382 - INFO - Domain Default found not creating
2025-08-11 07:02:14,519 - INFO - Project ctest-TestSvcRegr-17591135 not found, creating it
2025-08-11 07:02:15,049 - INFO - Created Project:ctest-TestSvcRegr-17591135, ID : 8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:16,459 - INFO - ================================================================================
2025-08-11 07:02:16,459 - INFO - STARTING TEST : test_svc_CEM_22032
2025-08-11 07:02:17,736 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.57': {'10.20.0.17': '0', '10.20.0.254': '0'}} with
2025-08-11 07:02:17,736 - INFO - Initial checks done. Running the testcase now
2025-08-11 07:02:17,736 - INFO -
2025-08-11 07:02:18,385 - DEBUG - Response for create_network : {'network': {'id': 'a1414104-e339-4561-9b54-2b05db71a922', 'name': 'ctest-mgmt_vn-71993945', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'ctest-mgmt_vn-71993945'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:18,608 - DEBUG - Response for create_subnet : {'subnet': {'id': '184a4ea0-bf35-4a66-a91f-e9202858ded5', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': 'a1414104-e339-4561-9b54-2b05db71a922', 'ip_version': 4, 'cidr': '211.121.159.64/26', 'allocation_pools': [{'start': '211.121.159.66', 'end': '211.121.159.126'}], 'gateway_ip': '211.121.159.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '211.121.159.66', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:18,631 - INFO - Created VN ctest-mgmt_vn-71993945
2025-08-11 07:02:18,642 - DEBUG - VN ctest-mgmt_vn-71993945 UUID is a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:18,715 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:19,008 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:19,038 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:19,053 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:19,142 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,161 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,173 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:19,184 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:19,193 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ef391906-85a0-4b8e-b8db-6f7a2dfeb79f
2025-08-11 07:02:19,200 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:19,200 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,210 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:19,225 - INFO - Verified VN network id 14 for VN a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,225 - INFO - Verifications in API Server for VN ctest-mgmt_vn-71993945 passed
2025-08-11 07:02:19,225 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,237 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:19,246 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:19,254 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ef391906-85a0-4b8e-b8db-6f7a2dfeb79f
2025-08-11 07:02:19,266 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11619640002840446305', 'uuid-lslong': '11192618278079015202'}, 'enable': 'true', 'created': '2025-08-11T07:02:18', 'last-modified': '2025-08-11T07:02:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.699461'}
2025-08-11 07:02:19,274 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:19,280 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11619640002840446305', 'uuid-lslong': '11192618278079015202'}, 'enable': 'true', 'created': '2025-08-11T07:02:18', 'last-modified': '2025-08-11T07:02:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.718946'}
2025-08-11 07:02:19,287 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:19,294 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11619640002840446305', 'uuid-lslong': '11192618278079015202'}, 'enable': 'true', 'created': '2025-08-11T07:02:18', 'last-modified': '2025-08-11T07:02:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.726040'}
2025-08-11 07:02:19,302 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:19,302 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-71993945 passed
2025-08-11 07:02:19,302 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-71993945 in API_Server ======
2025-08-11 07:02:19,302 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:19,310 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:19,324 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:19,417 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/a1414104-e339-4561-9b54-2b05db71a922
2025-08-11 07:02:19,429 - DEBUG - =>VN ctest-mgmt_vn-71993945 has no policy to be verified
2025-08-11 07:02:19,429 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:19,429 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:19,429 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:19,723 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:19,777 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945?flat'}
2025-08-11 07:02:19,777 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:19,777 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:20,067 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:20,121 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945?flat'}
2025-08-11 07:02:20,121 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:20,121 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:20,422 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:20,490 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945?flat'}
2025-08-11 07:02:20,490 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-71993945 is found in opserver
2025-08-11 07:02:20,517 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:20,528 - DEBUG - VRF ids for VN ctest-mgmt_vn-71993945: {}
2025-08-11 07:02:20,776 - DEBUG - Response for create_network : {'network': {'id': '13b5eede-5151-4955-a40d-ddb732021b09', 'name': 'service-left-vn-66317434', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'service-left-vn-66317434'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:20,992 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ff4f6912-a1e0-4119-9572-2761c1ef2e73', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': '13b5eede-5151-4955-a40d-ddb732021b09', 'ip_version': 4, 'cidr': '166.79.18.192/26', 'allocation_pools': [{'start': '166.79.18.194', 'end': '166.79.18.254'}], 'gateway_ip': '166.79.18.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.79.18.194', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:21,014 - INFO - Created VN service-left-vn-66317434
2025-08-11 07:02:21,027 - DEBUG - VN service-left-vn-66317434 UUID is 13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,103 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:21,109 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:21,120 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:21,209 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,223 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,234 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:21,244 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:21,252 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ff063b56-93a4-4a04-9e86-45cc173f3f9e
2025-08-11 07:02:21,258 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:21,258 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,268 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:21,286 - INFO - Verified VN network id 15 for VN 13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,287 - INFO - Verifications in API Server for VN service-left-vn-66317434 passed
2025-08-11 07:02:21,287 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,297 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:21,305 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:21,312 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/ff063b56-93a4-4a04-9e86-45cc173f3f9e
2025-08-11 07:02:21,324 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1420303896119691605', 'uuid-lslong': '11821348375805172489'}, 'enable': 'true', 'created': '2025-08-11T07:02:20', 'last-modified': '2025-08-11T07:02:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393759'}
2025-08-11 07:02:21,330 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:21,338 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1420303896119691605', 'uuid-lslong': '11821348375805172489'}, 'enable': 'true', 'created': '2025-08-11T07:02:20', 'last-modified': '2025-08-11T07:02:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.404092'}
2025-08-11 07:02:21,345 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:21,351 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1420303896119691605', 'uuid-lslong': '11821348375805172489'}, 'enable': 'true', 'created': '2025-08-11T07:02:20', 'last-modified': '2025-08-11T07:02:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.408364'}
2025-08-11 07:02:21,358 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:21,358 - INFO - On all control nodes, Config, RI and RT verification for VN service-left-vn-66317434 passed
2025-08-11 07:02:21,358 - DEBUG - ====Verifying policy data for service-left-vn-66317434 in API_Server ======
2025-08-11 07:02:21,358 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:21,365 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:21,377 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:21,476 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/13b5eede-5151-4955-a40d-ddb732021b09
2025-08-11 07:02:21,487 - DEBUG - =>VN service-left-vn-66317434 has no policy to be verified
2025-08-11 07:02:21,487 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:21,487 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:21,487 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:21,541 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434?flat'}
2025-08-11 07:02:21,541 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:21,541 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:21,594 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434?flat'}
2025-08-11 07:02:21,594 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:21,594 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:21,646 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434?flat'}
2025-08-11 07:02:21,646 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:service-left-vn-66317434 is found in opserver
2025-08-11 07:02:21,671 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:21,682 - DEBUG - VRF ids for VN service-left-vn-66317434: {}
2025-08-11 07:02:21,934 - DEBUG - Response for create_network : {'network': {'id': 'd2019bf6-9b80-4eb4-9d5d-d79ffd625280', 'name': 'service-right-vn-46555832', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'service-right-vn-46555832'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:22,260 - DEBUG - Response for create_subnet : {'subnet': {'id': '98220fd8-e8df-4d21-abe8-9d9114b4a480', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': 'd2019bf6-9b80-4eb4-9d5d-d79ffd625280', 'ip_version': 4, 'cidr': '206.231.158.128/26', 'allocation_pools': [{'start': '206.231.158.130', 'end': '206.231.158.190'}], 'gateway_ip': '206.231.158.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '206.231.158.130', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:22,289 - INFO - Created VN service-right-vn-46555832
2025-08-11 07:02:22,310 - DEBUG - VN service-right-vn-46555832 UUID is d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,392 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:22,399 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:22,415 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:22,512 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,533 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,545 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:22,554 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:22,563 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/4c1f514f-110e-4f18-9d71-8ad8fa5483a2
2025-08-11 07:02:22,571 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:22,572 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,582 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:22,598 - INFO - Verified VN network id 16 for VN d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,598 - INFO - Verifications in API Server for VN service-right-vn-46555832 passed
2025-08-11 07:02:22,598 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,611 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:22,618 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:22,625 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/4c1f514f-110e-4f18-9d71-8ad8fa5483a2
2025-08-11 07:02:22,639 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15132547706414714548', 'uuid-lslong': '11339456518939628160'}, 'enable': 'true', 'created': '2025-08-11T07:02:21', 'last-modified': '2025-08-11T07:02:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.452982'}
2025-08-11 07:02:22,645 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:22,651 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15132547706414714548', 'uuid-lslong': '11339456518939628160'}, 'enable': 'true', 'created': '2025-08-11T07:02:21', 'last-modified': '2025-08-11T07:02:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.469219'}
2025-08-11 07:02:22,659 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:22,665 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15132547706414714548', 'uuid-lslong': '11339456518939628160'}, 'enable': 'true', 'created': '2025-08-11T07:02:21', 'last-modified': '2025-08-11T07:02:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484281'}
2025-08-11 07:02:22,671 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:22,671 - INFO - On all control nodes, Config, RI and RT verification for VN service-right-vn-46555832 passed
2025-08-11 07:02:22,672 - DEBUG - ====Verifying policy data for service-right-vn-46555832 in API_Server ======
2025-08-11 07:02:22,672 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:22,678 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:22,690 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:22,778 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/d2019bf6-9b80-4eb4-9d5d-d79ffd625280
2025-08-11 07:02:22,789 - DEBUG - =>VN service-right-vn-46555832 has no policy to be verified
2025-08-11 07:02:22,789 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:22,789 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:22,789 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:22,845 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832?flat'}
2025-08-11 07:02:22,845 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:22,845 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:22,898 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832?flat'}
2025-08-11 07:02:22,898 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:22,898 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:22,948 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832?flat'}
2025-08-11 07:02:22,948 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:service-right-vn-46555832 is found in opserver
2025-08-11 07:02:22,972 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:22,984 - DEBUG - VRF ids for VN service-right-vn-46555832: {}
2025-08-11 07:02:23,037 - DEBUG - Services list from nova: [, , , ]
2025-08-11 07:02:24,018 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:02:25,530 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1)
2025-08-11 07:02:25,530 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-41541610']
2025-08-11 07:02:25,934 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-41541610'] created succesfully
2025-08-11 07:02:26,315 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 07:02:26,315 - DEBUG - Installing image tiny_nat_fw
2025-08-11 07:02:26,315 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 07:02:26,315 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:26,315 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:26,315 - DEBUG - Unzip image
2025-08-11 07:02:26,315 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:26,333 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 07:02:26,334 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-41541610']
2025-08-11 07:02:26,458 - INFO - Service template: ['default-domain', 'ctest-service_template_1-41541610'] deleted successfully.
2025-08-11 07:02:26,458 - INFO - Skip interface_detach for VM ctest-right_vm-51499204 in state BUILD
2025-08-11 07:02:26,459 - INFO - Deleting VM ctest-right_vm-51499204
2025-08-11 07:02:26,506 - ERROR - VM ctest-right_vm-51499204 has failed to come up
2025-08-11 07:02:26,507 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:26,507 - ERROR - VM ctest-right_vm-51499204 has failed to come up
2025-08-11 07:02:26,507 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:26,618 - INFO - Skip interface_detach for VM ctest-left_vm-76370414 in state BUILD
2025-08-11 07:02:26,618 - INFO - Deleting VM ctest-left_vm-76370414
2025-08-11 07:02:26,618 - ERROR - VM ctest-left_vm-76370414 has failed to come up
2025-08-11 07:02:26,618 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:26,618 - ERROR - VM ctest-left_vm-76370414 has failed to come up
2025-08-11 07:02:26,618 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:26,683 - INFO - Deleting VN service-right-vn-46555832
2025-08-11 07:02:26,736 - DEBUG - VN d2019bf6-9b80-4eb4-9d5d-d79ffd625280 still in use: Unable to complete operation on network d2019bf6-9b80-4eb4-9d5d-d79ffd625280. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9846ed45-305c-4768-aed2-32c5f6c1d061']
2025-08-11 07:02:26,736 - WARNING - Deleting VN service-right-vn-46555832 failed..Will retry
2025-08-11 07:02:28,874 - DEBUG - Response for deleting network ()
2025-08-11 07:02:28,874 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/ed9f9a38-4350-4470-9431-4ace59b5536d
2025-08-11 07:02:28,881 - DEBUG - Response Code: 404
2025-08-11 07:02:28,881 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:28,894 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:28,911 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:29,008 - INFO - Validated that VN service-right-vn-46555832 is not found in API Server
2025-08-11 07:02:29,021 - DEBUG - VN service-right-vn-46555832 is not present in Agent 10.0.0.49
2025-08-11 07:02:29,036 - DEBUG - VN service-right-vn-46555832 is not present in Agent 10.0.0.57
2025-08-11 07:02:29,036 - INFO - Validated that VN service-right-vn-46555832 is not in any agent
2025-08-11 07:02:29,070 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-right-vn-46555832 info
2025-08-11 07:02:29,071 - INFO - Deleting VN service-left-vn-66317434
2025-08-11 07:02:29,202 - DEBUG - Response for deleting network ()
2025-08-11 07:02:29,203 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/205c3c51-8706-4070-aa30-a5db45f89371
2025-08-11 07:02:29,209 - DEBUG - Response Code: 404
2025-08-11 07:02:29,209 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:29,224 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:29,241 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:29,349 - INFO - Validated that VN service-left-vn-66317434 is not found in API Server
2025-08-11 07:02:29,361 - DEBUG - VN service-left-vn-66317434 is not present in Agent 10.0.0.49
2025-08-11 07:02:29,371 - DEBUG - VN service-left-vn-66317434 is not present in Agent 10.0.0.57
2025-08-11 07:02:29,372 - INFO - Validated that VN service-left-vn-66317434 is not in any agent
2025-08-11 07:02:29,407 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN service-left-vn-66317434 info
2025-08-11 07:02:29,407 - INFO - Deleting VN ctest-mgmt_vn-71993945
2025-08-11 07:02:29,558 - DEBUG - Response for deleting network ()
2025-08-11 07:02:29,558 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bc8d5923-5073-4509-b48a-41c15df93f6f
2025-08-11 07:02:29,575 - DEBUG - Response Code: 404
2025-08-11 07:02:29,576 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:29,586 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:29,601 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:29,688 - INFO - Validated that VN ctest-mgmt_vn-71993945 is not found in API Server
2025-08-11 07:02:29,700 - DEBUG - VN ctest-mgmt_vn-71993945 is not present in Agent 10.0.0.49
2025-08-11 07:02:29,713 - DEBUG - VN ctest-mgmt_vn-71993945 is not present in Agent 10.0.0.57
2025-08-11 07:02:29,713 - INFO - Validated that VN ctest-mgmt_vn-71993945 is not in any agent
2025-08-11 07:02:29,749 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-71993945 info
2025-08-11 07:02:31,009 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 07:02:31,009 - INFO -
2025-08-11 07:02:31,009 - INFO - END TEST : test_svc_CEM_22032 : SKIPPED[0:00:15]
2025-08-11 07:02:31,010 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:02:31,013 - INFO - ================================================================================
2025-08-11 07:02:31,013 - INFO - STARTING TEST : test_svc_in_network_datapath
2025-08-11 07:02:32,278 - DEBUG - Nothing to compare xmpp stats {'10.0.0.49': {'10.20.0.17': '0', '10.20.0.25': '0'}, '10.0.0.57': {'10.20.0.17': '0', '10.20.0.254': '0'}} with
2025-08-11 07:02:32,278 - INFO - Initial checks done. Running the testcase now
2025-08-11 07:02:32,278 - INFO -
2025-08-11 07:02:32,516 - DEBUG - Response for create_network : {'network': {'id': '475173c3-4bb5-4e8b-8f1b-f5130189b17d', 'name': 'ctest-mgmt_vn-76320298', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'ctest-mgmt_vn-76320298'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:32,713 - DEBUG - Response for create_subnet : {'subnet': {'id': '44d31fe3-b72b-462f-9485-eaa131dd03e8', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': '475173c3-4bb5-4e8b-8f1b-f5130189b17d', 'ip_version': 4, 'cidr': '26.218.127.64/26', 'allocation_pools': [{'start': '26.218.127.66', 'end': '26.218.127.126'}], 'gateway_ip': '26.218.127.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.218.127.66', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:32,736 - INFO - Created VN ctest-mgmt_vn-76320298
2025-08-11 07:02:32,751 - DEBUG - VN ctest-mgmt_vn-76320298 UUID is 475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:32,818 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:32,824 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:32,837 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:32,931 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:32,943 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:32,953 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:32,963 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:32,971 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/0e71dca4-3891-4cbb-8b7b-e80628aa09fa
2025-08-11 07:02:32,979 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:32,979 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:32,990 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:33,007 - INFO - Verified VN network id 14 for VN 475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:33,007 - INFO - Verifications in API Server for VN ctest-mgmt_vn-76320298 passed
2025-08-11 07:02:33,007 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:33,017 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:33,025 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:33,033 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/0e71dca4-3891-4cbb-8b7b-e80628aa09fa
2025-08-11 07:02:33,046 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5139015932432436875', 'uuid-lslong': '10312105233773867389'}, 'enable': 'true', 'created': '2025-08-11T07:02:32', 'last-modified': '2025-08-11T07:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.393837'}
2025-08-11 07:02:33,052 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:33,058 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5139015932432436875', 'uuid-lslong': '10312105233773867389'}, 'enable': 'true', 'created': '2025-08-11T07:02:32', 'last-modified': '2025-08-11T07:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.407038'}
2025-08-11 07:02:33,065 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:33,071 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5139015932432436875', 'uuid-lslong': '10312105233773867389'}, 'enable': 'true', 'created': '2025-08-11T07:02:32', 'last-modified': '2025-08-11T07:02:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421210'}
2025-08-11 07:02:33,077 - DEBUG - Route Targets: ['target:64512:8000005']
2025-08-11 07:02:33,077 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-76320298 passed
2025-08-11 07:02:33,077 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-76320298 in API_Server ======
2025-08-11 07:02:33,078 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:33,084 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:33,096 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:33,184 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/475173c3-4bb5-4e8b-8f1b-f5130189b17d
2025-08-11 07:02:33,197 - DEBUG - =>VN ctest-mgmt_vn-76320298 has no policy to be verified
2025-08-11 07:02:33,197 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:33,197 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:33,197 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:33,211 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298?flat'}
2025-08-11 07:02:33,211 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:33,211 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:33,224 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298?flat'}
2025-08-11 07:02:33,224 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:33,224 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:33,238 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298?flat'}
2025-08-11 07:02:33,238 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:ctest-mgmt_vn-76320298 is found in opserver
2025-08-11 07:02:33,263 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:33,274 - DEBUG - VRF ids for VN ctest-mgmt_vn-76320298: {}
2025-08-11 07:02:33,561 - DEBUG - Response for create_network : {'network': {'id': '45f0c49c-6430-46c6-8c5b-1c0f955fccd2', 'name': 'ctest-left_vn-22275456', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'ctest-left_vn-22275456'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:33,822 - DEBUG - Response for create_subnet : {'subnet': {'id': '02c964db-1df5-4e83-9a43-22eb928c9461', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': '45f0c49c-6430-46c6-8c5b-1c0f955fccd2', 'ip_version': 4, 'cidr': '200.251.136.0/26', 'allocation_pools': [{'start': '200.251.136.2', 'end': '200.251.136.62'}], 'gateway_ip': '200.251.136.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '200.251.136.2', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:33,850 - INFO - Created VN ctest-left_vn-22275456
2025-08-11 07:02:33,861 - DEBUG - VN ctest-left_vn-22275456 UUID is 45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:33,954 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:33,962 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:33,975 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:34,081 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,097 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,109 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:34,122 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:34,131 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/23076bb8-f261-4481-a296-3f2545b9b327
2025-08-11 07:02:34,139 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:34,139 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,149 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:34,167 - INFO - Verified VN network id 15 for VN 45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,167 - INFO - Verifications in API Server for VN ctest-left_vn-22275456 passed
2025-08-11 07:02:34,168 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,179 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:34,187 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:34,196 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/23076bb8-f261-4481-a296-3f2545b9b327
2025-08-11 07:02:34,210 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5039744159002412742', 'uuid-lslong': '10113708241446751442'}, 'enable': 'true', 'created': '2025-08-11T07:02:33', 'last-modified': '2025-08-11T07:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440692'}
2025-08-11 07:02:34,216 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:34,222 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5039744159002412742', 'uuid-lslong': '10113708241446751442'}, 'enable': 'true', 'created': '2025-08-11T07:02:33', 'last-modified': '2025-08-11T07:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.458697'}
2025-08-11 07:02:34,229 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:34,234 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5039744159002412742', 'uuid-lslong': '10113708241446751442'}, 'enable': 'true', 'created': '2025-08-11T07:02:33', 'last-modified': '2025-08-11T07:02:33', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468280'}
2025-08-11 07:02:34,240 - DEBUG - Route Targets: ['target:64512:8000006']
2025-08-11 07:02:34,241 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-22275456 passed
2025-08-11 07:02:34,241 - DEBUG - ====Verifying policy data for ctest-left_vn-22275456 in API_Server ======
2025-08-11 07:02:34,241 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:34,247 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:34,259 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:34,355 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/45f0c49c-6430-46c6-8c5b-1c0f955fccd2
2025-08-11 07:02:34,372 - DEBUG - =>VN ctest-left_vn-22275456 has no policy to be verified
2025-08-11 07:02:34,372 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:34,372 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:34,372 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:34,387 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456?flat'}
2025-08-11 07:02:34,387 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:34,387 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:34,401 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456?flat'}
2025-08-11 07:02:34,401 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:34,401 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:34,413 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456?flat'}
2025-08-11 07:02:34,413 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:ctest-left_vn-22275456 is found in opserver
2025-08-11 07:02:34,436 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:34,449 - DEBUG - VRF ids for VN ctest-left_vn-22275456: {}
2025-08-11 07:02:34,730 - DEBUG - Response for create_network : {'network': {'id': '4c21fb6f-9159-42d9-8a7b-95caf8bc7d15', 'name': 'ctest-right_vn-91817741', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'project_id': '8362a15c354f46c28159d0aeaae371df', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-17591135', 'ctest-right_vn-91817741'], 'port_security_enabled': True, 'description': ''}}
2025-08-11 07:02:34,950 - DEBUG - Response for create_subnet : {'subnet': {'id': '494f05f2-d30f-4802-a4fa-d64e2ef9ac90', 'name': '', 'tenant_id': '8362a15c354f46c28159d0aeaae371df', 'network_id': '4c21fb6f-9159-42d9-8a7b-95caf8bc7d15', 'ip_version': 4, 'cidr': '121.50.192.0/26', 'allocation_pools': [{'start': '121.50.192.2', 'end': '121.50.192.62'}], 'gateway_ip': '121.50.192.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.50.192.2', 'tags': [], 'project_id': '8362a15c354f46c28159d0aeaae371df'}}
2025-08-11 07:02:34,971 - INFO - Created VN ctest-right_vn-91817741
2025-08-11 07:02:34,983 - DEBUG - VN ctest-right_vn-91817741 UUID is 4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,067 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:35,075 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:35,090 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:35,188 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,203 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,215 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:35,226 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:35,249 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/191cff1d-933a-4926-9765-5415262b9a70
2025-08-11 07:02:35,265 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:35,266 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,292 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:35,313 - INFO - Verified VN network id 16 for VN 4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,314 - INFO - Verifications in API Server for VN ctest-right_vn-91817741 passed
2025-08-11 07:02:35,314 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,326 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:35,337 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:35,351 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/191cff1d-933a-4926-9765-5415262b9a70
2025-08-11 07:02:35,367 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5485942277712462553', 'uuid-lslong': '9978734098358500629'}, 'enable': 'true', 'created': '2025-08-11T07:02:34', 'last-modified': '2025-08-11T07:02:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.470854'}
2025-08-11 07:02:35,377 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:35,383 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5485942277712462553', 'uuid-lslong': '9978734098358500629'}, 'enable': 'true', 'created': '2025-08-11T07:02:34', 'last-modified': '2025-08-11T07:02:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500027'}
2025-08-11 07:02:35,389 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:35,395 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-17591135', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5485942277712462553', 'uuid-lslong': '9978734098358500629'}, 'enable': 'true', 'created': '2025-08-11T07:02:34', 'last-modified': '2025-08-11T07:02:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.514565'}
2025-08-11 07:02:35,402 - DEBUG - Route Targets: ['target:64512:8000007']
2025-08-11 07:02:35,402 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-91817741 passed
2025-08-11 07:02:35,402 - DEBUG - ====Verifying policy data for ctest-right_vn-91817741 in API_Server ======
2025-08-11 07:02:35,402 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:35,409 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:35,421 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:35,546 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/4c21fb6f-9159-42d9-8a7b-95caf8bc7d15
2025-08-11 07:02:35,564 - DEBUG - =>VN ctest-right_vn-91817741 has no policy to be verified
2025-08-11 07:02:35,564 - DEBUG - Verifying the vn in opserver
2025-08-11 07:02:35,564 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741 virtual network link through opserver 10.0.0.254
2025-08-11 07:02:35,564 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-08-11 07:02:35,579 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741?flat'}
2025-08-11 07:02:35,579 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741 virtual network link through opserver 10.0.0.38
2025-08-11 07:02:35,579 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-08-11 07:02:35,592 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741?flat'}
2025-08-11 07:02:35,592 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741 virtual network link through opserver 10.0.0.50
2025-08-11 07:02:35,592 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2025-08-11 07:02:35,608 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741?flat'}
2025-08-11 07:02:35,608 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-17591135:ctest-right_vn-91817741 is found in opserver
2025-08-11 07:02:35,636 - DEBUG - Do not have enough data to verify VN in agent
2025-08-11 07:02:35,655 - DEBUG - VRF ids for VN ctest-right_vn-91817741: {}
2025-08-11 07:02:36,802 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-1)
2025-08-11 07:02:38,427 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3798-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3798-2)
2025-08-11 07:02:38,427 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92397133']
2025-08-11 07:02:38,715 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92397133'] created succesfully
2025-08-11 07:02:39,123 - DEBUG - Image by name tiny_nat_fw not found
2025-08-11 07:02:39,123 - DEBUG - Installing image tiny_nat_fw
2025-08-11 07:02:39,123 - DEBUG - Image info {'name': 'tinycore-in-network-nat.qcow2.gz', 'webserver': '', 'location': '/images/tinycore/', 'username': 'tc', 'password': 'secret', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hypervisor_type=qemu', 'name_docker': 'phusion-baseimage-enablesshd', 'vmdk': 'service_chain_images/nat_service/tiny_nat_fw/tinycore-in-network-nat-flat.vmdk', 'shrinked_vmdk': "'no'"}
2025-08-11 07:02:39,124 - DEBUG - Image build path file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:39,124 - DEBUG - Download image from local file file:///contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:39,124 - DEBUG - Unzip image
2025-08-11 07:02:39,124 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/tinycore-in-network-nat.qcow2.gz
2025-08-11 07:02:39,141 - INFO - image /contrail-test/images/tinycore-in-network-nat.qcow2 is not found
2025-08-11 07:02:39,141 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92397133']
2025-08-11 07:02:39,233 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92397133'] deleted successfully.
2025-08-11 07:02:39,233 - INFO - Skip interface_detach for VM ctest-right_vm-36887097 in state BUILD
2025-08-11 07:02:39,233 - INFO - Deleting VM ctest-right_vm-36887097
2025-08-11 07:02:39,234 - ERROR - VM ctest-right_vm-36887097 has failed to come up
2025-08-11 07:02:39,234 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:39,234 - ERROR - VM ctest-right_vm-36887097 has failed to come up
2025-08-11 07:02:39,235 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:39,356 - INFO - Skip interface_detach for VM ctest-left_vm-96670737 in state BUILD
2025-08-11 07:02:39,356 - INFO - Deleting VM ctest-left_vm-96670737
2025-08-11 07:02:39,356 - ERROR - VM ctest-left_vm-96670737 has failed to come up
2025-08-11 07:02:39,357 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:39,357 - ERROR - VM ctest-left_vm-96670737 has failed to come up
2025-08-11 07:02:39,357 - ERROR - Nova failed to get host of the VM
2025-08-11 07:02:39,473 - INFO - Deleting VN ctest-right_vn-91817741
2025-08-11 07:02:39,524 - DEBUG - VN 4c21fb6f-9159-42d9-8a7b-95caf8bc7d15 still in use: Unable to complete operation on network 4c21fb6f-9159-42d9-8a7b-95caf8bc7d15. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d6a0f93a-0227-4e54-bed6-a63d4289d39b']
2025-08-11 07:02:39,524 - WARNING - Deleting VN ctest-right_vn-91817741 failed..Will retry
2025-08-11 07:02:41,705 - DEBUG - Response for deleting network ()
2025-08-11 07:02:41,705 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/80c3c040-72e7-4532-86ed-06510c14c528
2025-08-11 07:02:41,713 - DEBUG - Response Code: 404
2025-08-11 07:02:41,713 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:41,721 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:41,747 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:41,848 - INFO - Validated that VN ctest-right_vn-91817741 is not found in API Server
2025-08-11 07:02:41,862 - DEBUG - VN ctest-right_vn-91817741 is not present in Agent 10.0.0.49
2025-08-11 07:02:41,875 - DEBUG - VN ctest-right_vn-91817741 is not present in Agent 10.0.0.57
2025-08-11 07:02:41,875 - INFO - Validated that VN ctest-right_vn-91817741 is not in any agent
2025-08-11 07:02:41,908 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-91817741 info
2025-08-11 07:02:41,909 - INFO - Deleting VN ctest-left_vn-22275456
2025-08-11 07:02:42,065 - DEBUG - Response for deleting network ()
2025-08-11 07:02:42,065 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/456632e8-3bd7-4912-a66a-aa28a591d84e
2025-08-11 07:02:42,077 - DEBUG - Response Code: 404
2025-08-11 07:02:42,077 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:42,097 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:42,118 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:42,217 - INFO - Validated that VN ctest-left_vn-22275456 is not found in API Server
2025-08-11 07:02:42,229 - DEBUG - VN ctest-left_vn-22275456 is not present in Agent 10.0.0.49
2025-08-11 07:02:42,242 - DEBUG - VN ctest-left_vn-22275456 is not present in Agent 10.0.0.57
2025-08-11 07:02:42,242 - INFO - Validated that VN ctest-left_vn-22275456 is not in any agent
2025-08-11 07:02:42,275 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-22275456 info
2025-08-11 07:02:42,275 - INFO - Deleting VN ctest-mgmt_vn-76320298
2025-08-11 07:02:42,400 - DEBUG - Response for deleting network ()
2025-08-11 07:02:42,401 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/bb615d47-bc21-4b31-a946-509d18959ac5
2025-08-11 07:02:42,411 - DEBUG - Response Code: 404
2025-08-11 07:02:42,411 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-08-11 07:02:42,417 - DEBUG - Requesting: http://10.0.0.254:8082/domain/0566ad86-51fd-4570-9dcc-fd1153322979
2025-08-11 07:02:42,438 - DEBUG - Requesting: http://10.0.0.254:8082/project/8362a15c-354f-46c2-8159-d0aeaae371df
2025-08-11 07:02:42,530 - INFO - Validated that VN ctest-mgmt_vn-76320298 is not found in API Server
2025-08-11 07:02:42,544 - DEBUG - VN ctest-mgmt_vn-76320298 is not present in Agent 10.0.0.49
2025-08-11 07:02:42,557 - DEBUG - VN ctest-mgmt_vn-76320298 is not present in Agent 10.0.0.57
2025-08-11 07:02:42,557 - INFO - Validated that VN ctest-mgmt_vn-76320298 is not in any agent
2025-08-11 07:02:42,591 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-76320298 info
2025-08-11 07:02:43,875 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 07:02:43,875 - INFO -
2025-08-11 07:02:43,875 - INFO - END TEST : test_svc_in_network_datapath : SKIPPED[0:00:12]
2025-08-11 07:02:43,875 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:02:44,622 - INFO - Deleted project: ctest-TestSvcRegr-17591135, ID : 8362a15c-354f-46c2-8159-d0aeaae371df