2025-06-24 15:47:01,743 - INFO - Domain Default found not creating 2025-06-24 15:47:01,910 - INFO - Project ctest-TestSvcRegr-87737044 not found, creating it 2025-06-24 15:47:02,397 - INFO - Created Project:ctest-TestSvcRegr-87737044, ID : 5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:03,832 - INFO - ================================================================================ 2025-06-24 15:47:03,832 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-06-24 15:47:04,089 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2025-06-24 15:47:04,089 - INFO - Initial checks done. Running the testcase now 2025-06-24 15:47:04,089 - INFO - 2025-06-24 15:47:04,635 - DEBUG - Response for create_network : {'network': {'id': 'c871224f-7322-4529-8288-28f5a128beda', 'name': 'ctest-mgmt_vn-54679892', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-mgmt_vn-54679892'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 15:47:04,796 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fee88074-4056-4aa1-b92e-5dd0569cfd33', 'name': '', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'network_id': 'c871224f-7322-4529-8288-28f5a128beda', 'ip_version': 4, 'cidr': '76.231.233.128/26', 'allocation_pools': [{'start': '76.231.233.130', 'end': '76.231.233.190'}], 'gateway_ip': '76.231.233.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.231.233.130', 'tags': [], 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b'}} 2025-06-24 15:47:04,814 - INFO - Created VN ctest-mgmt_vn-54679892 2025-06-24 15:47:04,868 - DEBUG - VN ctest-mgmt_vn-54679892 UUID is c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:04,975 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:05,268 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:05,301 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:05,318 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:05,396 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,408 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,418 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:47:05,430 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:47:05,438 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e922a339-708b-4864-8bdd-2bd7242ed8b5 2025-06-24 15:47:05,447 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-24 15:47:05,447 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,459 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:47:05,477 - INFO - Verified VN network id 8 for VN c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,477 - INFO - Verifications in API Server for VN ctest-mgmt_vn-54679892 passed 2025-06-24 15:47:05,477 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,487 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:47:05,496 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:47:05,505 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/e922a339-708b-4864-8bdd-2bd7242ed8b5 2025-06-24 15:47:05,520 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87737044', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14443363204583277865', 'uuid-lslong': '9405812857199181530'}, 'enable': 'true', 'created': '2025-06-24T15:47:04', 'last-modified': '2025-06-24T15:47:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.746175'} 2025-06-24 15:47:05,526 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-24 15:47:05,526 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-54679892 passed 2025-06-24 15:47:05,526 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-54679892 in API_Server ====== 2025-06-24 15:47:05,526 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:05,533 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:05,548 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:05,623 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c871224f-7322-4529-8288-28f5a128beda 2025-06-24 15:47:05,634 - DEBUG - =>VN ctest-mgmt_vn-54679892 has no policy to be verified 2025-06-24 15:47:05,634 - DEBUG - Verifying the vn in opserver 2025-06-24 15:47:05,634 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 virtual network link through opserver 10.0.0.38 2025-06-24 15:47:05,634 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-24 15:47:05,924 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-24 15:47:05,986 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892?flat'} 2025-06-24 15:47:05,986 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is found in opserver 2025-06-24 15:47:05,999 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 15:47:06,004 - DEBUG - VRF ids for VN ctest-mgmt_vn-54679892: {} 2025-06-24 15:47:06,189 - DEBUG - Response for create_network : {'network': {'id': 'c168b387-c5bc-4d2f-b6d8-147ce1ea0219', 'name': 'ctest-left_vn-36895728', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-left_vn-36895728'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 15:47:06,397 - DEBUG - Response for create_subnet : {'subnet': {'id': '1114f22b-f6ce-4e84-9b98-cd385210ce7f', 'name': '', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'network_id': 'c168b387-c5bc-4d2f-b6d8-147ce1ea0219', 'ip_version': 4, 'cidr': '160.169.149.0/26', 'allocation_pools': [{'start': '160.169.149.2', 'end': '160.169.149.62'}], 'gateway_ip': '160.169.149.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.169.149.2', 'tags': [], 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b'}} 2025-06-24 15:47:06,415 - INFO - Created VN ctest-left_vn-36895728 2025-06-24 15:47:06,468 - DEBUG - VN ctest-left_vn-36895728 UUID is c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,582 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:06,588 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:06,605 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:06,676 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,687 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,697 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:47:06,705 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:47:06,712 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/8b07cb29-9d2a-42be-82c2-236d5bec5a14 2025-06-24 15:47:06,719 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-24 15:47:06,719 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,728 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:47:06,741 - INFO - Verified VN network id 9 for VN c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,741 - INFO - Verifications in API Server for VN ctest-left_vn-36895728 passed 2025-06-24 15:47:06,741 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,750 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:47:06,756 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:47:06,764 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/8b07cb29-9d2a-42be-82c2-236d5bec5a14 2025-06-24 15:47:06,775 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87737044', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13936586442617408815', 'uuid-lslong': '13175303236471095833'}, 'enable': 'true', 'created': '2025-06-24T15:47:06', 'last-modified': '2025-06-24T15:47:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.411507'} 2025-06-24 15:47:06,781 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-24 15:47:06,781 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-36895728 passed 2025-06-24 15:47:06,781 - DEBUG - ====Verifying policy data for ctest-left_vn-36895728 in API_Server ====== 2025-06-24 15:47:06,781 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:06,787 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:06,799 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:06,873 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/c168b387-c5bc-4d2f-b6d8-147ce1ea0219 2025-06-24 15:47:06,883 - DEBUG - =>VN ctest-left_vn-36895728 has no policy to be verified 2025-06-24 15:47:06,883 - DEBUG - Verifying the vn in opserver 2025-06-24 15:47:06,883 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 virtual network link through opserver 10.0.0.38 2025-06-24 15:47:06,883 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-24 15:47:06,895 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728?flat'} 2025-06-24 15:47:06,895 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 is found in opserver 2025-06-24 15:47:06,906 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 15:47:06,911 - DEBUG - VRF ids for VN ctest-left_vn-36895728: {} 2025-06-24 15:47:07,094 - DEBUG - Response for create_network : {'network': {'id': '3b080fa2-809e-4c6c-b7c9-540ed994b805', 'name': 'ctest-right_vn-43108473', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-right_vn-43108473'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 15:47:07,290 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8680b7f-72ed-4d4a-b727-48cbe59be77e', 'name': '', 'tenant_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b', 'network_id': '3b080fa2-809e-4c6c-b7c9-540ed994b805', 'ip_version': 4, 'cidr': '182.16.55.64/26', 'allocation_pools': [{'start': '182.16.55.66', 'end': '182.16.55.126'}], 'gateway_ip': '182.16.55.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '182.16.55.66', 'tags': [], 'project_id': '5547b2e9b92d4c34b4c4eeb3236f7f0b'}} 2025-06-24 15:47:07,313 - INFO - Created VN ctest-right_vn-43108473 2025-06-24 15:47:07,368 - DEBUG - VN ctest-right_vn-43108473 UUID is 3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,476 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:07,482 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:07,495 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:07,565 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,576 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,586 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:47:07,593 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:47:07,601 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9faf1192-20f0-46ae-9792-6b30fc911d57 2025-06-24 15:47:07,608 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-06-24 15:47:07,608 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,617 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:47:07,630 - INFO - Verified VN network id 10 for VN 3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,630 - INFO - Verifications in API Server for VN ctest-right_vn-43108473 passed 2025-06-24 15:47:07,631 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,640 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:47:07,647 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:47:07,653 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9faf1192-20f0-46ae-9792-6b30fc911d57 2025-06-24 15:47:07,665 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-87737044', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4253667038668409964', 'uuid-lslong': '13243208602016331781'}, 'enable': 'true', 'created': '2025-06-24T15:47:07', 'last-modified': '2025-06-24T15:47:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.405520'} 2025-06-24 15:47:07,671 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-06-24 15:47:07,671 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-43108473 passed 2025-06-24 15:47:07,671 - DEBUG - ====Verifying policy data for ctest-right_vn-43108473 in API_Server ====== 2025-06-24 15:47:07,671 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:07,677 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:07,688 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:07,762 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/3b080fa2-809e-4c6c-b7c9-540ed994b805 2025-06-24 15:47:07,773 - DEBUG - =>VN ctest-right_vn-43108473 has no policy to be verified 2025-06-24 15:47:07,773 - DEBUG - Verifying the vn in opserver 2025-06-24 15:47:07,773 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 virtual network link through opserver 10.0.0.38 2025-06-24 15:47:07,773 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-06-24 15:47:07,784 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473?flat'} 2025-06-24 15:47:07,784 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 is found in opserver 2025-06-24 15:47:07,794 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 15:47:07,799 - DEBUG - VRF ids for VN ctest-right_vn-43108473: {} 2025-06-24 15:47:07,851 - DEBUG - Services list from nova: [, , ] 2025-06-24 15:47:08,861 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3539-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3539-1) 2025-06-24 15:47:10,014 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3539-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3539-1) 2025-06-24 15:47:10,014 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-06198789'] 2025-06-24 15:47:10,392 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-06198789'] created succesfully 2025-06-24 15:47:10,729 - DEBUG - Image by name cirros_in_net not found 2025-06-24 15:47:10,729 - DEBUG - Installing image cirros_in_net 2025-06-24 15:47:10,729 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'} 2025-06-24 15:47:10,729 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-06-24 15:47:10,729 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-06-24 15:47:10,742 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz 2025-06-24 15:47:10,855 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-06-24 15:47:10,856 - DEBUG - Unzip image 2025-06-24 15:47:10,856 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-06-24 15:47:11,035 - DEBUG - Try to create image in user context 2025-06-24 15:47:11,256 - DEBUG - Image has been created in user context 2025-06-24 15:47:11,256 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-06-24 15:47:11,270 - DEBUG - Downloaded image has been removed 2025-06-24 15:47:11,284 - DEBUG - Uploaded image status = active 2025-06-24 15:47:12,422 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3539-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3539-1) 2025-06-24 15:47:12,423 - INFO - Waiting for VM ctest-pt_svm0-52996237 to be up.. 2025-06-24 15:47:12,465 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-06-24 15:47:17,551 - DEBUG - VM is in ACTIVE state now 2025-06-24 15:47:17,551 - INFO - VM name : ctest-pt_svm0-52996237 2025-06-24 15:47:17,630 - DEBUG - VM ctest-pt_svm0-52996237 ID is f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:47:17,648 - DEBUG - VM ctest-pt_svm0-52996237 launched on Node cn-jenkins-deploy-platform-ansible-os-3539-1 2025-06-24 15:47:17,920 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:47:17,929 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/749ebd91-14c6-4a82-a39e-d7cbc365150e 2025-06-24 15:47:17,939 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8c6562fe-f066-44a5-ab56-be154a6f00b3 2025-06-24 15:47:17,950 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c3cf32be-7985-4691-96c2-ea537687c55b 2025-06-24 15:47:21,278 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-06-24 15:47:21,279 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-52996237 failed! 2025-06-24 15:47:21,336 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is 76.231.233.129 and allocation pool is NOT set 2025-06-24 15:47:25,406 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-06-24 15:47:25,406 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-52996237 failed! 2025-06-24 15:47:25,464 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is 76.231.233.129 and allocation pool is NOT set 2025-06-24 15:47:29,533 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-06-24 15:47:29,533 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-52996237 failed! 2025-06-24 15:47:29,592 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is 76.231.233.129 and allocation pool is NOT set 2025-06-24 15:47:33,667 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-06-24 15:47:33,667 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-52996237 failed! 2025-06-24 15:47:33,724 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is 76.231.233.129 and allocation pool is NOT set 2025-06-24 15:47:35,791 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=3.77 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.787 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.787/2.276/3.766/1.489 ms') 2025-06-24 15:47:35,791 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-52996237 passed 2025-06-24 15:47:35,948 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:35,948 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:36,014 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-06-24 15:47:36,102 - DEBUG - VM ctest-pt_svm0-52996237 is NOT ready for SSH connections, VM status: ACTIVE 2025-06-24 15:47:41,104 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:41,104 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:41,164 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-06-24 15:47:41,252 - DEBUG - VM ctest-pt_svm0-52996237 is NOT ready for SSH connections, VM status: ACTIVE 2025-06-24 15:47:46,252 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:46,252 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:46,308 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-06-24 15:47:46,401 - DEBUG - VM ctest-pt_svm0-52996237 is NOT ready for SSH connections, VM status: ACTIVE 2025-06-24 15:47:51,402 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:51,402 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:51,560 - DEBUG - VM ctest-pt_svm0-52996237 is ready for SSH connections 2025-06-24 15:47:51,712 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:51,712 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:51,864 - DEBUG - VM ctest-pt_svm0-52996237 is ready for SSH connections 2025-06-24 15:47:52,016 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:52,016 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-52996237, IP 76.231.233.131, Port 22 2025-06-24 15:47:52,167 - DEBUG - VM ctest-pt_svm0-52996237 is ready for SSH connections 2025-06-24 15:47:52,173 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-si-41074432'] 2025-06-24 15:47:53,072 - INFO - Waiting for VM ctest-left_vm-86439553 to be up.. 2025-06-24 15:47:53,153 - DEBUG - VM is in ACTIVE state now 2025-06-24 15:47:53,153 - INFO - VM name : ctest-left_vm-86439553 2025-06-24 15:47:53,247 - DEBUG - VM ctest-left_vm-86439553 ID is 456ed5a4-65a6-4492-bd1b-4b89bc78d283 2025-06-24 15:47:53,248 - DEBUG - VM ctest-left_vm-86439553 launched on Node cn-jenkins-deploy-platform-ansible-os-3539-1 2025-06-24 15:47:53,335 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/456ed5a4-65a6-4492-bd1b-4b89bc78d283 2025-06-24 15:47:53,348 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/4e14561f-e03d-42ea-803c-7c69cd25196d 2025-06-24 15:47:54,590 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.492 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.492/1.776/3.060/1.284 ms') 2025-06-24 15:47:54,590 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-86439553 passed 2025-06-24 15:47:54,744 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:54,744 - DEBUG - Waiting to SSH to VM ctest-left_vm-86439553, IP 160.169.149.3, Port 22 2025-06-24 15:47:54,899 - DEBUG - VM ctest-left_vm-86439553 is ready for SSH connections 2025-06-24 15:47:54,899 - INFO - Waiting for VM ctest-right_vm-25379807 to be up.. 2025-06-24 15:47:54,985 - DEBUG - VM is in ACTIVE state now 2025-06-24 15:47:54,985 - INFO - VM name : ctest-right_vm-25379807 2025-06-24 15:47:55,107 - DEBUG - VM ctest-right_vm-25379807 ID is d9e1192b-8369-4049-a9ae-0332451bc593 2025-06-24 15:47:55,107 - DEBUG - VM ctest-right_vm-25379807 launched on Node cn-jenkins-deploy-platform-ansible-os-3539-1 2025-06-24 15:47:55,202 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/d9e1192b-8369-4049-a9ae-0332451bc593 2025-06-24 15:47:55,213 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/5b976c0b-1995-46dd-b375-6f54d6b7ed98 2025-06-24 15:47:56,458 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.77 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.537 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.537/1.651/2.766/1.114 ms') 2025-06-24 15:47:56,458 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-25379807 passed 2025-06-24 15:47:56,612 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:47:56,612 - DEBUG - Waiting to SSH to VM ctest-right_vm-25379807, IP 182.16.55.67, Port 22 2025-06-24 15:47:56,778 - DEBUG - VM ctest-right_vm-25379807 is ready for SSH connections 2025-06-24 15:47:56,785 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = 65535], action_list = simple_action = pass, gateway_name = None, apply_service = ['default-domain:ctest-TestSvcRegr-87737044:ctest-si-41074432'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-06-24 15:47:57,224 - INFO - Attaching policy ctest-policy-80069780 to vn ctest-left_vn-36895728 2025-06-24 15:47:57,756 - INFO - Attaching policy ctest-policy-80069780 to vn ctest-right_vn-43108473 2025-06-24 15:47:58,208 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-06198789'] created succesfully 2025-06-24 15:47:58,440 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-si-41074432'] created succesfully 2025-06-24 15:47:58,440 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:58,449 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:58,464 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:58,551 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/68ae006e-e4a1-4983-bace-0710020c16e7 2025-06-24 15:47:58,568 - DEBUG - SI 'ctest-si-41074432' has service template ref to ctest-service_template_1-06198789 2025-06-24 15:47:58,568 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:47:58,575 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:47:58,588 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:47:58,668 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/68ae006e-e4a1-4983-bace-0710020c16e7 2025-06-24 15:47:58,681 - DEBUG - Requesting: http://10.0.0.38:8082/port-tuple/57f154d4-a9e1-4d97-b96d-b83f0daa9b87 2025-06-24 15:47:58,694 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/749ebd91-14c6-4a82-a39e-d7cbc365150e 2025-06-24 15:47:58,706 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8c6562fe-f066-44a5-ab56-be154a6f00b3 2025-06-24 15:47:58,718 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c3cf32be-7985-4691-96c2-ea537687c55b 2025-06-24 15:47:58,940 - DEBUG - VM is in ACTIVE state now 2025-06-24 15:47:59,376 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:47:59,385 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/749ebd91-14c6-4a82-a39e-d7cbc365150e 2025-06-24 15:47:59,397 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8c6562fe-f066-44a5-ab56-be154a6f00b3 2025-06-24 15:47:59,407 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c3cf32be-7985-4691-96c2-ea537687c55b 2025-06-24 15:47:59,418 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/538dc1cd-9ac2-4107-be11-e45679dc74ec 2025-06-24 15:47:59,426 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/8dfc9e50-7122-4ec3-8605-b2b84f59ff05 2025-06-24 15:47:59,435 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/d0dcc651-5d1a-40e1-9d8c-870221fc66de 2025-06-24 15:47:59,443 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/78edba03-ef82-4d6c-be46-255de0d4da2f 2025-06-24 15:47:59,452 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/9b5431ca-e184-41b8-beff-d7c3706cbc38 2025-06-24 15:47:59,462 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/c466a88b-e521-4af8-b0f6-fcd1f996e8af 2025-06-24 15:47:59,471 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/6b3559b6-f548-43be-b1f7-2538c39f932f 2025-06-24 15:47:59,483 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/940b7502-ff3d-465f-bc13-0a724549cc3f 2025-06-24 15:47:59,491 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/e057b6ed-9276-4530-a5d9-f31992222c58 2025-06-24 15:47:59,879 - DEBUG - VM is in ACTIVE state now 2025-06-24 15:47:59,879 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:47:59,888 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/749ebd91-14c6-4a82-a39e-d7cbc365150e 2025-06-24 15:47:59,898 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8c6562fe-f066-44a5-ab56-be154a6f00b3 2025-06-24 15:47:59,909 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c3cf32be-7985-4691-96c2-ea537687c55b 2025-06-24 15:48:01,144 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.21 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.607 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.607/0.910/1.213/0.303 ms') 2025-06-24 15:48:01,144 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-52996237 passed 2025-06-24 15:48:01,185 - DEBUG - VM ctest-pt_svm0-52996237 Tap interface: {'index': '6', 'name': 'tap8c6562fe-f0', 'uuid': '8c6562fe-f066-44a5-ab56-be154a6f00b3', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728:ctest-left_vn-36895728', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '160.169.149.4', 'mac_addr': '02:8c:65:62:fe:f0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '29', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:8c6562fe-f066-44a5-ab56-be154a6f00b3', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '50', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '749ebd91-14c6-4a82-a39e-d7cbc365150e', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:01,185 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728:ctest-left_vn-36895728 2025-06-24 15:48:01,193 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728:ctest-left_vn-36895728', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.17:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-06-24 15:48:01,362 - DEBUG - VM ctest-pt_svm0-52996237 : Tap interface tap8c6562fe-f0 is set to Active 2025-06-24 15:48:01,362 - DEBUG - Tap interface tap8c6562fe-f0 detail : {'index': '6', 'name': 'tap8c6562fe-f0', 'uuid': '8c6562fe-f066-44a5-ab56-be154a6f00b3', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728:ctest-left_vn-36895728', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '44', 'l2_label': '48', 'vxlan_id': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '160.169.149.4', 'mac_addr': '02:8c:65:62:fe:f0', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '29', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:8c6562fe-f066-44a5-ab56-be154a6f00b3', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '50', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '749ebd91-14c6-4a82-a39e-d7cbc365150e', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:01,564 - DEBUG - Starting Layer 2 verification in Agent 2025-06-24 15:48:01,580 - DEBUG - Layer 2 path is seen for VM MAC 02:8c:65:62:fe:f0 in agent 10.0.0.38 2025-06-24 15:48:01,580 - DEBUG - Active layer 2 route in agent is present for VMI tap8c6562fe-f0 2025-06-24 15:48:01,580 - DEBUG - L2 label(48) matches bw route table and interface table 2025-06-24 15:48:01,587 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 is found in Agent of node 10.0.0.38 2025-06-24 15:48:01,805 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 is consistent in agent 10.0.0.38 2025-06-24 15:48:01,805 - DEBUG - Route for VM IP ['160.169.149.4'] is consistent in agent 10.0.0.38 2025-06-24 15:48:01,805 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 verification for VM ctest-pt_svm0-52996237 in Agent 10.0.0.38 passed 2025-06-24 15:48:02,004 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-06-24 15:48:02,016 - DEBUG - Route for VM MAC 02:8c:65:62:fe:f0 is consistent in agent 10.0.0.38 2025-06-24 15:48:02,038 - DEBUG - VM ctest-pt_svm0-52996237 Tap interface: {'index': '5', 'name': 'tapc3cf32be-79', 'uuid': 'c3cf32be-7985-4691-96c2-ea537687c55b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892:ctest-mgmt_vn-54679892', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '8', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '76.231.233.131', 'mac_addr': '02:c3:cf:32:be:79', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:c3cf32be-7985-4691-96c2-ea537687c55b', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:02,038 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892:ctest-mgmt_vn-54679892 2025-06-24 15:48:02,044 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892:ctest-mgmt_vn-54679892', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892', 'table_label': '-1', 'vxlan_id': '8', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.17:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-06-24 15:48:02,215 - DEBUG - VM ctest-pt_svm0-52996237 : Tap interface tapc3cf32be-79 is set to Active 2025-06-24 15:48:02,215 - DEBUG - Tap interface tapc3cf32be-79 detail : {'index': '5', 'name': 'tapc3cf32be-79', 'uuid': 'c3cf32be-7985-4691-96c2-ea537687c55b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892:ctest-mgmt_vn-54679892', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '39', 'l2_label': '43', 'vxlan_id': '8', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '76.231.233.131', 'mac_addr': '02:c3:cf:32:be:79', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:c3cf32be-7985-4691-96c2-ea537687c55b', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:02,412 - DEBUG - Starting Layer 2 verification in Agent 2025-06-24 15:48:02,424 - DEBUG - Layer 2 path is seen for VM MAC 02:c3:cf:32:be:79 in agent 10.0.0.38 2025-06-24 15:48:02,424 - DEBUG - Active layer 2 route in agent is present for VMI tapc3cf32be-79 2025-06-24 15:48:02,424 - DEBUG - L2 label(43) matches bw route table and interface table 2025-06-24 15:48:02,431 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is found in Agent of node 10.0.0.38 2025-06-24 15:48:02,646 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 is consistent in agent 10.0.0.38 2025-06-24 15:48:02,646 - DEBUG - Route for VM IP ['76.231.233.131'] is consistent in agent 10.0.0.38 2025-06-24 15:48:02,646 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-mgmt_vn-54679892 verification for VM ctest-pt_svm0-52996237 in Agent 10.0.0.38 passed 2025-06-24 15:48:02,848 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-06-24 15:48:02,860 - DEBUG - Route for VM MAC 02:c3:cf:32:be:79 is consistent in agent 10.0.0.38 2025-06-24 15:48:02,882 - DEBUG - VM ctest-pt_svm0-52996237 Tap interface: {'index': '7', 'name': 'tap749ebd91-14', 'uuid': '749ebd91-14c6-4a82-a39e-d7cbc365150e', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473:ctest-right_vn-43108473', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '182.16.55.68', 'mac_addr': '02:74:9e:bd:91:14', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:749ebd91-14c6-4a82-a39e-d7cbc365150e', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '8c6562fe-f066-44a5-ab56-be154a6f00b3', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:02,882 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473:ctest-right_vn-43108473 2025-06-24 15:48:02,888 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473:ctest-right_vn-43108473', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.17:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-06-24 15:48:03,049 - DEBUG - VM ctest-pt_svm0-52996237 : Tap interface tap749ebd91-14 is set to Active 2025-06-24 15:48:03,049 - DEBUG - Tap interface tap749ebd91-14 detail : {'index': '7', 'name': 'tap749ebd91-14', 'uuid': '749ebd91-14c6-4a82-a39e-d7cbc365150e', 'vrf_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473:ctest-right_vn-43108473', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '49', 'l2_label': '53', 'vxlan_id': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473', 'vm_uuid': 'f856553c-c65b-4620-88cc-7c9639ad77d4', 'vm_name': 'ctest-pt_svm0-52996237', 'ip_addr': '182.16.55.68', 'mac_addr': '02:74:9e:bd:91:14', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '30', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-87737044:749ebd91-14c6-4a82-a39e-d7cbc365150e', 'sg_uuid_list': ['dfe25242-8a8d-48bf-881e-ba4da1b73a8d'], 'static_route_list': None, 'vm_project_uuid': '5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '8c6562fe-f066-44a5-ab56-be154a6f00b3', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-06-24 15:48:03,244 - DEBUG - Starting Layer 2 verification in Agent 2025-06-24 15:48:03,256 - DEBUG - Layer 2 path is seen for VM MAC 02:74:9e:bd:91:14 in agent 10.0.0.38 2025-06-24 15:48:03,256 - DEBUG - Active layer 2 route in agent is present for VMI tap749ebd91-14 2025-06-24 15:48:03,256 - DEBUG - L2 label(53) matches bw route table and interface table 2025-06-24 15:48:03,262 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 is found in Agent of node 10.0.0.38 2025-06-24 15:48:03,484 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 is consistent in agent 10.0.0.38 2025-06-24 15:48:03,484 - DEBUG - Route for VM IP ['182.16.55.68'] is consistent in agent 10.0.0.38 2025-06-24 15:48:03,484 - DEBUG - VN default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 verification for VM ctest-pt_svm0-52996237 in Agent 10.0.0.38 passed 2025-06-24 15:48:03,676 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-06-24 15:48:03,688 - DEBUG - Route for VM MAC 02:74:9e:bd:91:14 is consistent in agent 10.0.0.38 2025-06-24 15:48:03,688 - INFO - VM ctest-pt_svm0-52996237 verifications in Compute nodes passed 2025-06-24 15:48:03,689 - DEBUG - The SVMs in the SI are : [] 2025-06-24 15:48:03,689 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:48:03,697 - DEBUG - Service VM for SI 'ctest-si-41074432' is launched 2025-06-24 15:48:03,697 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:48:03,703 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:48:03,716 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:48:03,795 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/68ae006e-e4a1-4983-bace-0710020c16e7 2025-06-24 15:48:03,805 - DEBUG - SI ctest-si-41074432 has Port Tuple: ['ctest-port_tuple-32570995'] 2025-06-24 15:48:03,908 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-87737044:ctest-left_vn-36895728 2025-06-24 15:48:04,052 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-87737044:ctest-right_vn-43108473 2025-06-24 15:48:04,211 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,226 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,226 - INFO - Route to 182.16.55.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-3539-1, and SI ctest-si-41074432 is seen as the NH 2025-06-24 15:48:04,387 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,396 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,397 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,403 - INFO - Route to 160.169.149.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-3539-1, and SI ctest-si-41074432 is seen as the NH 2025-06-24 15:48:04,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-24 15:48:04,403 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 182.16.55.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-06-24 15:48:04,403 - DEBUG - ping -s 56 -c 3 -W 1 182.16.55.67 2025-06-24 15:48:08,371 - DEBUG - PING 182.16.55.67 (182.16.55.67): 56 data bytes 64 bytes from 182.16.55.67: seq=0 ttl=61 time=5.396 ms 64 bytes from 182.16.55.67: seq=1 ttl=61 time=1.531 ms 64 bytes from 182.16.55.67: seq=2 ttl=61 time=1.678 ms --- 182.16.55.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.531/2.868/5.396 ms 2025-06-24 15:48:08,371 - INFO - Ping to IP 182.16.55.67 from VM ctest-left_vm-86439553 passed 2025-06-24 15:48:08,371 - INFO - Dettaching policy ctest-policy-80069780 from vn ctest-right_vn-43108473 2025-06-24 15:48:08,580 - INFO - Dettaching policy ctest-policy-80069780 from vn ctest-left_vn-36895728 2025-06-24 15:48:08,849 - INFO - Deleted policy ctest-policy-80069780 2025-06-24 15:48:08,850 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-si-41074432'] 2025-06-24 15:48:08,943 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-87737044', 'ctest-si-41074432'] 2025-06-24 15:48:08,943 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:48:08,949 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:48:08,964 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:48:09,038 - DEBUG - Service instance ctest-si-41074432 removed from api server 2025-06-24 15:48:09,038 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/f856553c-c65b-4620-88cc-7c9639ad77d4 2025-06-24 15:48:09,047 - DEBUG - All Service VMs unlinked from SI ctest-si-41074432 2025-06-24 15:48:09,052 - DEBUG - 0 svc intances found in all projects. They are [] 2025-06-24 15:48:09,052 - DEBUG - 0 svc intances found in current project. They are [] 2025-06-24 15:48:09,100 - DEBUG - 0 svc intances found in all projects. They are [] 2025-06-24 15:48:09,100 - DEBUG - 0 svc intances found in current project. They are [] 2025-06-24 15:48:09,100 - INFO - Deleting VM ctest-pt_svm0-52996237 2025-06-24 15:48:09,178 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-06198789'] 2025-06-24 15:48:09,275 - INFO - Service template: ['default-domain', 'ctest-service_template_1-06198789'] deleted successfully. 2025-06-24 15:48:09,276 - INFO - Deleting VM ctest-right_vm-25379807 2025-06-24 15:48:09,339 - INFO - Deleting VM ctest-left_vm-86439553 2025-06-24 15:48:09,429 - INFO - Deleting VN ctest-right_vn-43108473 2025-06-24 15:48:09,471 - DEBUG - VN 3b080fa2-809e-4c6c-b7c9-540ed994b805 still in use: Unable to complete operation on network 3b080fa2-809e-4c6c-b7c9-540ed994b805. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-60089404-3cae-4164-8a2a-143e4b04954d'] 2025-06-24 15:48:09,472 - WARNING - Deleting VN ctest-right_vn-43108473 failed..Will retry 2025-06-24 15:48:11,599 - DEBUG - Response for deleting network () 2025-06-24 15:48:11,600 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/581e3007-7dfc-4851-9364-0b54612fc5d2 2025-06-24 15:48:11,609 - DEBUG - Response Code: 404 2025-06-24 15:48:11,609 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:48:11,616 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:48:11,670 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:48:11,746 - INFO - Validated that VN ctest-right_vn-43108473 is not found in API Server 2025-06-24 15:48:11,757 - DEBUG - VN ctest-right_vn-43108473 is not present in Agent 10.0.0.38 2025-06-24 15:48:11,757 - INFO - Validated that VN ctest-right_vn-43108473 is not in any agent 2025-06-24 15:48:11,774 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-43108473 info 2025-06-24 15:48:11,774 - INFO - Deleting VN ctest-left_vn-36895728 2025-06-24 15:48:11,884 - DEBUG - Response for deleting network () 2025-06-24 15:48:11,884 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/5512532b-f9d6-4c2e-94f3-4ba2eee4d803 2025-06-24 15:48:11,890 - DEBUG - Response Code: 404 2025-06-24 15:48:11,890 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:48:11,895 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:48:11,950 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:48:12,026 - INFO - Validated that VN ctest-left_vn-36895728 is not found in API Server 2025-06-24 15:48:12,037 - DEBUG - VN ctest-left_vn-36895728 is not present in Agent 10.0.0.38 2025-06-24 15:48:12,037 - INFO - Validated that VN ctest-left_vn-36895728 is not in any agent 2025-06-24 15:48:12,052 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-36895728 info 2025-06-24 15:48:12,052 - INFO - Deleting VN ctest-mgmt_vn-54679892 2025-06-24 15:48:12,164 - DEBUG - Response for deleting network () 2025-06-24 15:48:12,164 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/9002de71-eadb-4e68-997a-5b7aa278fcb6 2025-06-24 15:48:12,171 - DEBUG - Response Code: 404 2025-06-24 15:48:12,171 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-06-24 15:48:12,178 - DEBUG - Requesting: http://10.0.0.38:8082/domain/b2b7d340-c169-44ad-b2bb-224b0c1fe6ef 2025-06-24 15:48:12,235 - DEBUG - Requesting: http://10.0.0.38:8082/project/5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b 2025-06-24 15:48:12,313 - INFO - Validated that VN ctest-mgmt_vn-54679892 is not found in API Server 2025-06-24 15:48:12,323 - DEBUG - VN ctest-mgmt_vn-54679892 is not present in Agent 10.0.0.38 2025-06-24 15:48:12,324 - INFO - Validated that VN ctest-mgmt_vn-54679892 is not in any agent 2025-06-24 15:48:12,339 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-54679892 info 2025-06-24 15:48:12,599 - DEBUG - No XMPP flaps were noticed during the test 2025-06-24 15:48:12,600 - INFO -
2025-06-24 15:48:12,600 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:09]
2025-06-24 15:48:12,600 - INFO - -------------------------------------------------------------------------------- 2025-06-24 15:48:13,290 - INFO - Deleted project: ctest-TestSvcRegr-87737044, ID : 5547b2e9-b92d-4c34-b4c4-eeb3236f7f0b