2024-09-19 21:56:48,519 - INFO - Domain Default found not creating 2024-09-19 21:56:48,682 - INFO - Project ctest-TestSvcRegr-37450460 not found, creating it 2024-09-19 21:56:49,160 - INFO - Created Project:ctest-TestSvcRegr-37450460, ID : 123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:50,650 - INFO - ================================================================================ 2024-09-19 21:56:50,650 - INFO - STARTING TEST : test_svc_in_network_datapath 2024-09-19 21:56:50,906 - DEBUG - Nothing to compare xmpp stats {'10.0.0.28': {'10.20.0.13': '0'}} with 2024-09-19 21:56:50,906 - INFO - Initial checks done. Running the testcase now 2024-09-19 21:56:50,906 - INFO - 2024-09-19 21:56:51,537 - DEBUG - Response for create_network : {'network': {'id': '477dddf2-8645-4dee-9610-05aa94d4b5c9', 'name': 'ctest-mgmt_vn-15527038', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'project_id': '123a38ab10484618aed82fc46da55396', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-mgmt_vn-15527038'], 'port_security_enabled': True, 'description': ''}} 2024-09-19 21:56:51,738 - DEBUG - Response for create_subnet : {'subnet': {'id': '0c721d1d-6cc9-4618-aa02-28c3d055f13f', 'name': '', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'network_id': '477dddf2-8645-4dee-9610-05aa94d4b5c9', 'ip_version': 4, 'cidr': '150.81.220.0/26', 'allocation_pools': [{'start': '150.81.220.2', 'end': '150.81.220.62'}], 'gateway_ip': '150.81.220.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.81.220.2', 'tags': [], 'project_id': '123a38ab10484618aed82fc46da55396'}} 2024-09-19 21:56:51,759 - INFO - Created VN ctest-mgmt_vn-15527038 2024-09-19 21:56:51,816 - DEBUG - VN ctest-mgmt_vn-15527038 UUID is 477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:51,939 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:52,244 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:52,275 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:52,292 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:52,376 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,392 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,406 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:56:52,415 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:56:52,424 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/f8599b7a-dd46-4fb0-bb83-f394f03c7e3c 2024-09-19 21:56:52,434 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-09-19 21:56:52,434 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,450 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:56:52,473 - INFO - Verified VN network id 9 for VN 477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,473 - INFO - Verifications in API Server for VN ctest-mgmt_vn-15527038 passed 2024-09-19 21:56:52,473 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,488 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:56:52,499 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:56:52,508 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/f8599b7a-dd46-4fb0-bb83-f394f03c7e3c 2024-09-19 21:56:52,524 - DEBUG - Control-node 10.0.0.28 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-37450460', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5151517582486228462', 'uuid-lslong': '10813148935516108233'}, 'enable': 'true', 'created': '2024-09-19T21:56:51', 'last-modified': '2024-09-19T21:56:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.821864'} 2024-09-19 21:56:52,535 - DEBUG - Route Targets: ['target:64512:8000005'] 2024-09-19 21:56:52,535 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-15527038 passed 2024-09-19 21:56:52,536 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-15527038 in API_Server ====== 2024-09-19 21:56:52,536 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:52,543 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:52,557 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:52,649 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/477dddf2-8645-4dee-9610-05aa94d4b5c9 2024-09-19 21:56:52,665 - DEBUG - =>VN ctest-mgmt_vn-15527038 has no policy to be verified 2024-09-19 21:56:52,665 - DEBUG - Verifying the vn in opserver 2024-09-19 21:56:52,665 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 virtual network link through opserver 10.0.0.28 2024-09-19 21:56:52,665 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-09-19 21:56:52,967 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-09-19 21:56:53,041 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038', 'href': 'http://10.0.0.28:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038?flat'} 2024-09-19 21:56:53,041 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is found in opserver 2024-09-19 21:56:53,052 - DEBUG - Do not have enough data to verify VN in agent 2024-09-19 21:56:53,057 - DEBUG - VRF ids for VN ctest-mgmt_vn-15527038: {} 2024-09-19 21:56:53,271 - DEBUG - Response for create_network : {'network': {'id': '45c29af0-ed53-4fb8-b5ce-c7eb41f78a78', 'name': 'ctest-left_vn-97460600', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'project_id': '123a38ab10484618aed82fc46da55396', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-left_vn-97460600'], 'port_security_enabled': True, 'description': ''}} 2024-09-19 21:56:53,456 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd27f455-3be1-465b-a2c2-287c98a01d0c', 'name': '', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'network_id': '45c29af0-ed53-4fb8-b5ce-c7eb41f78a78', 'ip_version': 4, 'cidr': '193.40.156.128/26', 'allocation_pools': [{'start': '193.40.156.130', 'end': '193.40.156.190'}], 'gateway_ip': '193.40.156.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.40.156.130', 'tags': [], 'project_id': '123a38ab10484618aed82fc46da55396'}} 2024-09-19 21:56:53,480 - INFO - Created VN ctest-left_vn-97460600 2024-09-19 21:56:53,536 - DEBUG - VN ctest-left_vn-97460600 UUID is 45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,656 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:53,663 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:53,676 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:53,766 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,782 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,796 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:56:53,804 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:56:53,812 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/b8bdee95-409c-4ff4-89c7-8bf783bf042b 2024-09-19 21:56:53,819 - DEBUG - Route Targets: ['target:64512:8000006'] 2024-09-19 21:56:53,819 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,831 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:56:53,850 - INFO - Verified VN network id 10 for VN 45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,850 - INFO - Verifications in API Server for VN ctest-left_vn-97460600 passed 2024-09-19 21:56:53,850 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:53,865 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:56:53,877 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:56:53,887 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/b8bdee95-409c-4ff4-89c7-8bf783bf042b 2024-09-19 21:56:53,900 - DEBUG - Control-node 10.0.0.28 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-37450460', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5026750493663383480', 'uuid-lslong': '13100628179305335416'}, 'enable': 'true', 'created': '2024-09-19T21:56:53', 'last-modified': '2024-09-19T21:56:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.471494'} 2024-09-19 21:56:53,909 - DEBUG - Route Targets: ['target:64512:8000006'] 2024-09-19 21:56:53,909 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-97460600 passed 2024-09-19 21:56:53,909 - DEBUG - ====Verifying policy data for ctest-left_vn-97460600 in API_Server ====== 2024-09-19 21:56:53,909 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:53,916 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:53,933 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:54,023 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/45c29af0-ed53-4fb8-b5ce-c7eb41f78a78 2024-09-19 21:56:54,037 - DEBUG - =>VN ctest-left_vn-97460600 has no policy to be verified 2024-09-19 21:56:54,037 - DEBUG - Verifying the vn in opserver 2024-09-19 21:56:54,037 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 virtual network link through opserver 10.0.0.28 2024-09-19 21:56:54,037 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-09-19 21:56:54,048 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600', 'href': 'http://10.0.0.28:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600?flat'} 2024-09-19 21:56:54,048 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 is found in opserver 2024-09-19 21:56:54,060 - DEBUG - Do not have enough data to verify VN in agent 2024-09-19 21:56:54,066 - DEBUG - VRF ids for VN ctest-left_vn-97460600: {} 2024-09-19 21:56:54,278 - DEBUG - Response for create_network : {'network': {'id': '7fd2d0bc-e844-4150-bf6c-6453e454a9d5', 'name': 'ctest-right_vn-67801524', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'project_id': '123a38ab10484618aed82fc46da55396', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-right_vn-67801524'], 'port_security_enabled': True, 'description': ''}} 2024-09-19 21:56:54,438 - DEBUG - Response for create_subnet : {'subnet': {'id': '12f1722d-b0c8-4683-ba85-fb8323cdef77', 'name': '', 'tenant_id': '123a38ab10484618aed82fc46da55396', 'network_id': '7fd2d0bc-e844-4150-bf6c-6453e454a9d5', 'ip_version': 4, 'cidr': '126.160.142.64/26', 'allocation_pools': [{'start': '126.160.142.66', 'end': '126.160.142.126'}], 'gateway_ip': '126.160.142.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '126.160.142.66', 'tags': [], 'project_id': '123a38ab10484618aed82fc46da55396'}} 2024-09-19 21:56:54,456 - INFO - Created VN ctest-right_vn-67801524 2024-09-19 21:56:54,512 - DEBUG - VN ctest-right_vn-67801524 UUID is 7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,623 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:54,628 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:54,641 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:54,725 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,739 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,753 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:56:54,762 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:56:54,771 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/36ec9e86-9c5c-42f5-bb22-54edc78cb5fb 2024-09-19 21:56:54,778 - DEBUG - Route Targets: ['target:64512:8000007'] 2024-09-19 21:56:54,778 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,792 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:56:54,812 - INFO - Verified VN network id 11 for VN 7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,813 - INFO - Verifications in API Server for VN ctest-right_vn-67801524 passed 2024-09-19 21:56:54,813 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,827 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:56:54,835 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:56:54,843 - DEBUG - Requesting: http://10.0.0.28:8082/route-target/36ec9e86-9c5c-42f5-bb22-54edc78cb5fb 2024-09-19 21:56:54,857 - DEBUG - Control-node 10.0.0.28 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-37450460', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9210653697695301968', 'uuid-lslong': '13793510070204803541'}, 'enable': 'true', 'created': '2024-09-19T21:56:54', 'last-modified': '2024-09-19T21:56:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.453054'} 2024-09-19 21:56:54,864 - DEBUG - Route Targets: ['target:64512:8000007'] 2024-09-19 21:56:54,864 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-67801524 passed 2024-09-19 21:56:54,864 - DEBUG - ====Verifying policy data for ctest-right_vn-67801524 in API_Server ====== 2024-09-19 21:56:54,864 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:56:54,870 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:56:54,883 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:56:54,964 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-network/7fd2d0bc-e844-4150-bf6c-6453e454a9d5 2024-09-19 21:56:54,978 - DEBUG - =>VN ctest-right_vn-67801524 has no policy to be verified 2024-09-19 21:56:54,978 - DEBUG - Verifying the vn in opserver 2024-09-19 21:56:54,978 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 virtual network link through opserver 10.0.0.28 2024-09-19 21:56:54,978 - DEBUG - Requesting: http://10.0.0.28:8081/analytics/uves/virtual-networks 2024-09-19 21:56:54,989 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524', 'href': 'http://10.0.0.28:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524?flat'} 2024-09-19 21:56:54,989 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 is found in opserver 2024-09-19 21:56:54,999 - DEBUG - Do not have enough data to verify VN in agent 2024-09-19 21:56:55,004 - DEBUG - VRF ids for VN ctest-right_vn-67801524: {} 2024-09-19 21:56:55,060 - DEBUG - Services list from nova: [, , ] 2024-09-19 21:56:56,102 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2007-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2007-1) 2024-09-19 21:56:57,244 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2007-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2007-1) 2024-09-19 21:56:57,245 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-43118750'] 2024-09-19 21:56:57,648 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-43118750'] created succesfully 2024-09-19 21:56:57,991 - DEBUG - Image by name cirros_in_net not found 2024-09-19 21:56:57,991 - DEBUG - Installing image cirros_in_net 2024-09-19 21:56:57,991 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'vcpath': '/images/vcenter/', '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'} 2024-09-19 21:56:57,991 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2024-09-19 21:56:57,991 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2024-09-19 21:56:58,003 - 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 2024-09-19 21:56:58,516 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2024-09-19 21:56:58,516 - DEBUG - Unzip image 2024-09-19 21:56:58,516 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2024-09-19 21:56:58,667 - DEBUG - Try to create image in user context 2024-09-19 21:56:58,897 - DEBUG - Image has been created in user context 2024-09-19 21:56:58,898 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2024-09-19 21:56:58,913 - DEBUG - Downloaded image has been removed 2024-09-19 21:56:58,928 - DEBUG - Uploaded image status = active 2024-09-19 21:57:00,065 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2007-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2007-1) 2024-09-19 21:57:00,065 - INFO - Waiting for VM ctest-pt_svm0-01442445 to be up.. 2024-09-19 21:57:00,111 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2024-09-19 21:57:05,225 - DEBUG - VM is in ACTIVE state now 2024-09-19 21:57:05,225 - INFO - VM name : ctest-pt_svm0-01442445 2024-09-19 21:57:05,328 - DEBUG - VM ctest-pt_svm0-01442445 ID is dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:57:05,348 - DEBUG - VM ctest-pt_svm0-01442445 launched on Node cn-jenkins-deploy-platform-ansible-os-2007-1 2024-09-19 21:57:05,681 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:57:05,695 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/3938ee0e-440f-4526-a40e-92c693b93f08 2024-09-19 21:57:05,710 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/9e9dd0e5-31f7-4515-a16e-aa5b165da7c7 2024-09-19 21:57:05,731 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/e476ff3f-388f-4d6c-bd04-272c7ba4d218 2024-09-19 21:57:09,036 - 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 1003ms') 2024-09-19 21:57:09,036 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-01442445 failed! 2024-09-19 21:57:09,096 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is 150.81.220.1 and allocation pool is NOT set 2024-09-19 21:57:13,169 - 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 1007ms') 2024-09-19 21:57:13,169 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-01442445 failed! 2024-09-19 21:57:13,228 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is 150.81.220.1 and allocation pool is NOT set 2024-09-19 21:57:17,298 - 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 1007ms') 2024-09-19 21:57:17,299 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-01442445 failed! 2024-09-19 21:57:17,356 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is 150.81.220.1 and allocation pool is NOT set 2024-09-19 21:57:19,418 - 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=12.3 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=2.42 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.424/7.352/12.280/4.928 ms') 2024-09-19 21:57:19,418 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-01442445 passed 2024-09-19 21:57:19,576 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:19,576 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:19,641 - 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': ''} 2024-09-19 21:57:19,728 - DEBUG - VM ctest-pt_svm0-01442445 is NOT ready for SSH connections, VM status: ACTIVE 2024-09-19 21:57:24,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:24,729 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:24,784 - 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': ''} 2024-09-19 21:57:24,867 - DEBUG - VM ctest-pt_svm0-01442445 is NOT ready for SSH connections, VM status: ACTIVE 2024-09-19 21:57:29,868 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:29,869 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:29,924 - 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': ''} 2024-09-19 21:57:30,013 - DEBUG - VM ctest-pt_svm0-01442445 is NOT ready for SSH connections, VM status: ACTIVE 2024-09-19 21:57:35,014 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:35,014 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:35,082 - 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': ''} 2024-09-19 21:57:35,169 - DEBUG - VM ctest-pt_svm0-01442445 is NOT ready for SSH connections, VM status: ACTIVE 2024-09-19 21:57:40,170 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:40,170 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:40,228 - 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': ''} 2024-09-19 21:57:40,321 - DEBUG - VM ctest-pt_svm0-01442445 is NOT ready for SSH connections, VM status: ACTIVE 2024-09-19 21:57:45,321 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:45,321 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:45,495 - DEBUG - VM ctest-pt_svm0-01442445 is ready for SSH connections 2024-09-19 21:57:45,648 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:45,648 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:45,804 - DEBUG - VM ctest-pt_svm0-01442445 is ready for SSH connections 2024-09-19 21:57:45,964 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:45,964 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-01442445, IP 150.81.220.3, Port 22 2024-09-19 21:57:46,115 - DEBUG - VM ctest-pt_svm0-01442445 is ready for SSH connections 2024-09-19 21:57:46,122 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-si-26316059'] 2024-09-19 21:57:47,060 - INFO - Waiting for VM ctest-left_vm-53599747 to be up.. 2024-09-19 21:57:47,142 - DEBUG - VM is in ACTIVE state now 2024-09-19 21:57:47,142 - INFO - VM name : ctest-left_vm-53599747 2024-09-19 21:57:47,221 - DEBUG - VM ctest-left_vm-53599747 ID is 874a9730-b0c0-4886-9c17-f80c94a8f09b 2024-09-19 21:57:47,221 - DEBUG - VM ctest-left_vm-53599747 launched on Node cn-jenkins-deploy-platform-ansible-os-2007-1 2024-09-19 21:57:47,314 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/874a9730-b0c0-4886-9c17-f80c94a8f09b 2024-09-19 21:57:47,323 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/74a1502e-0682-48e4-8dd5-3ef4de83f2a5 2024-09-19 21:57:48,575 - 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=2.97 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.387 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.387/1.677/2.968/1.290 ms') 2024-09-19 21:57:48,575 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-53599747 passed 2024-09-19 21:57:48,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:48,732 - DEBUG - Waiting to SSH to VM ctest-left_vm-53599747, IP 193.40.156.131, Port 22 2024-09-19 21:57:48,895 - DEBUG - VM ctest-left_vm-53599747 is ready for SSH connections 2024-09-19 21:57:48,895 - INFO - Waiting for VM ctest-right_vm-63750573 to be up.. 2024-09-19 21:57:48,978 - DEBUG - VM is in ACTIVE state now 2024-09-19 21:57:48,978 - INFO - VM name : ctest-right_vm-63750573 2024-09-19 21:57:49,056 - DEBUG - VM ctest-right_vm-63750573 ID is 3c77272a-bd9b-46fc-8d20-791dac353f91 2024-09-19 21:57:49,056 - DEBUG - VM ctest-right_vm-63750573 launched on Node cn-jenkins-deploy-platform-ansible-os-2007-1 2024-09-19 21:57:49,132 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/3c77272a-bd9b-46fc-8d20-791dac353f91 2024-09-19 21:57:49,142 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/81014a1c-74d4-4c6c-8899-2b6a4978a9f2 2024-09-19 21:57:50,395 - 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.97 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.384 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.384/1.677/2.970/1.293 ms') 2024-09-19 21:57:50,395 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-63750573 passed 2024-09-19 21:57:50,552 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:50,552 - DEBUG - Waiting to SSH to VM ctest-right_vm-63750573, IP 126.160.142.67, Port 22 2024-09-19 21:57:50,716 - DEBUG - VM ctest-right_vm-63750573 is ready for SSH connections 2024-09-19 21:57:50,722 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600, 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-37450460:ctest-right_vn-67801524, 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-37450460:ctest-si-26316059'], 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] 2024-09-19 21:57:51,176 - INFO - Attaching policy ctest-policy-56199966 to vn ctest-left_vn-97460600 2024-09-19 21:57:51,732 - INFO - Attaching policy ctest-policy-56199966 to vn ctest-right_vn-67801524 2024-09-19 21:57:52,188 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-43118750'] created succesfully 2024-09-19 21:57:52,440 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-si-26316059'] created succesfully 2024-09-19 21:57:52,440 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:57:52,447 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:57:52,466 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:57:52,552 - DEBUG - Requesting: http://10.0.0.28:8082/service-instance/8c526ca0-4d8e-4cee-bd12-878e98b3b388 2024-09-19 21:57:52,576 - DEBUG - SI 'ctest-si-26316059' has service template ref to ctest-service_template_1-43118750 2024-09-19 21:57:52,576 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:57:52,583 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:57:52,602 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:57:52,690 - DEBUG - Requesting: http://10.0.0.28:8082/service-instance/8c526ca0-4d8e-4cee-bd12-878e98b3b388 2024-09-19 21:57:52,701 - DEBUG - Requesting: http://10.0.0.28:8082/port-tuple/3d367e3d-65f2-41fd-94f6-905e701b514d 2024-09-19 21:57:52,713 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/3938ee0e-440f-4526-a40e-92c693b93f08 2024-09-19 21:57:52,734 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/9e9dd0e5-31f7-4515-a16e-aa5b165da7c7 2024-09-19 21:57:52,749 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/e476ff3f-388f-4d6c-bd04-272c7ba4d218 2024-09-19 21:57:53,003 - DEBUG - VM is in ACTIVE state now 2024-09-19 21:57:53,464 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:57:53,474 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/3938ee0e-440f-4526-a40e-92c693b93f08 2024-09-19 21:57:53,491 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/9e9dd0e5-31f7-4515-a16e-aa5b165da7c7 2024-09-19 21:57:53,505 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/e476ff3f-388f-4d6c-bd04-272c7ba4d218 2024-09-19 21:57:53,520 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/47c57663-7270-433d-9f0d-f285db015273 2024-09-19 21:57:53,529 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/93df6b06-eac9-44d5-8920-a09937757bfe 2024-09-19 21:57:53,539 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/cad40911-6335-4bc1-8f15-4c4d71068a8c 2024-09-19 21:57:53,549 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/078d11b4-f290-4fed-83e8-bb058d0465a4 2024-09-19 21:57:53,559 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/0e57af8a-b4ca-499a-8ce3-506cadab5cb1 2024-09-19 21:57:53,569 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/1fdb9ca6-c0fb-4111-830b-c3f74d2d2907 2024-09-19 21:57:53,580 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/1aee4d04-44e4-459d-92b7-fe8d6f2af1b2 2024-09-19 21:57:53,588 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/58190aad-8ac8-466e-b9f3-0559d39a555c 2024-09-19 21:57:53,598 - DEBUG - Requesting: http://10.0.0.28:8082/instance-ip/bbf7e999-fd61-4fc6-b58a-d4bb858a4e2f 2024-09-19 21:57:54,053 - DEBUG - VM is in ACTIVE state now 2024-09-19 21:57:54,053 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:57:54,064 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/3938ee0e-440f-4526-a40e-92c693b93f08 2024-09-19 21:57:54,080 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/9e9dd0e5-31f7-4515-a16e-aa5b165da7c7 2024-09-19 21:57:54,096 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/e476ff3f-388f-4d6c-bd04-272c7ba4d218 2024-09-19 21:57:55,358 - 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.10 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.532 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.532/0.816/1.101/0.284 ms') 2024-09-19 21:57:55,358 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-01442445 passed 2024-09-19 21:57:55,397 - DEBUG - VM ctest-pt_svm0-01442445 Tap interface: {'index': '6', 'name': 'tap3938ee0e-44', 'uuid': '3938ee0e-440f-4526-a40e-92c693b93f08', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600:ctest-left_vn-97460600', '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': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '193.40.156.132', 'mac_addr': '02:39:38:ee:0e:44', '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-37450460:3938ee0e-440f-4526-a40e-92c693b93f08', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': '9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', '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': []} 2024-09-19 21:57:55,397 - DEBUG - Agent 10.0.0.28 vrf name: default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600:ctest-left_vn-97460600 2024-09-19 21:57:55,402 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600:ctest-left_vn-97460600', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.13:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2024-09-19 21:57:55,573 - DEBUG - VM ctest-pt_svm0-01442445 : Tap interface tap3938ee0e-44 is set to Active 2024-09-19 21:57:55,573 - DEBUG - Tap interface tap3938ee0e-44 detail : {'index': '6', 'name': 'tap3938ee0e-44', 'uuid': '3938ee0e-440f-4526-a40e-92c693b93f08', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600:ctest-left_vn-97460600', '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': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '193.40.156.132', 'mac_addr': '02:39:38:ee:0e:44', '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-37450460:3938ee0e-440f-4526-a40e-92c693b93f08', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': '9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', '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': []} 2024-09-19 21:57:55,772 - DEBUG - Starting Layer 2 verification in Agent 2024-09-19 21:57:55,787 - DEBUG - Layer 2 path is seen for VM MAC 02:39:38:ee:0e:44 in agent 10.0.0.28 2024-09-19 21:57:55,787 - DEBUG - Active layer 2 route in agent is present for VMI tap3938ee0e-44 2024-09-19 21:57:55,787 - DEBUG - L2 label(48) matches bw route table and interface table 2024-09-19 21:57:55,794 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 is found in Agent of node 10.0.0.28 2024-09-19 21:57:56,014 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 is consistent in agent 10.0.0.28 2024-09-19 21:57:56,014 - DEBUG - Route for VM IP ['193.40.156.132'] is consistent in agent 10.0.0.28 2024-09-19 21:57:56,014 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 verification for VM ctest-pt_svm0-01442445 in Agent 10.0.0.28 passed 2024-09-19 21:57:56,216 - DEBUG - Starting all layer 2 verification in agent 10.0.0.28 2024-09-19 21:57:56,228 - DEBUG - Route for VM MAC 02:39:38:ee:0e:44 is consistent in agent 10.0.0.28 2024-09-19 21:57:56,252 - DEBUG - VM ctest-pt_svm0-01442445 Tap interface: {'index': '5', 'name': 'tape476ff3f-38', 'uuid': 'e476ff3f-388f-4d6c-bd04-272c7ba4d218', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038:ctest-mgmt_vn-15527038', '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': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '150.81.220.3', 'mac_addr': '02:e4:76:ff:3f:38', '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-37450460:e476ff3f-388f-4d6c-bd04-272c7ba4d218', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', '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': []} 2024-09-19 21:57:56,252 - DEBUG - Agent 10.0.0.28 vrf name: default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038:ctest-mgmt_vn-15527038 2024-09-19 21:57:56,257 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038:ctest-mgmt_vn-15527038', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.13:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2024-09-19 21:57:56,429 - DEBUG - VM ctest-pt_svm0-01442445 : Tap interface tape476ff3f-38 is set to Active 2024-09-19 21:57:56,430 - DEBUG - Tap interface tape476ff3f-38 detail : {'index': '5', 'name': 'tape476ff3f-38', 'uuid': 'e476ff3f-388f-4d6c-bd04-272c7ba4d218', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038:ctest-mgmt_vn-15527038', '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': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '150.81.220.3', 'mac_addr': '02:e4:76:ff:3f:38', '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-37450460:e476ff3f-388f-4d6c-bd04-272c7ba4d218', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', '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': []} 2024-09-19 21:57:56,632 - DEBUG - Starting Layer 2 verification in Agent 2024-09-19 21:57:56,645 - DEBUG - Layer 2 path is seen for VM MAC 02:e4:76:ff:3f:38 in agent 10.0.0.28 2024-09-19 21:57:56,645 - DEBUG - Active layer 2 route in agent is present for VMI tape476ff3f-38 2024-09-19 21:57:56,645 - DEBUG - L2 label(43) matches bw route table and interface table 2024-09-19 21:57:56,651 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is found in Agent of node 10.0.0.28 2024-09-19 21:57:56,878 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 is consistent in agent 10.0.0.28 2024-09-19 21:57:56,879 - DEBUG - Route for VM IP ['150.81.220.3'] is consistent in agent 10.0.0.28 2024-09-19 21:57:56,879 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-mgmt_vn-15527038 verification for VM ctest-pt_svm0-01442445 in Agent 10.0.0.28 passed 2024-09-19 21:57:57,080 - DEBUG - Starting all layer 2 verification in agent 10.0.0.28 2024-09-19 21:57:57,092 - DEBUG - Route for VM MAC 02:e4:76:ff:3f:38 is consistent in agent 10.0.0.28 2024-09-19 21:57:57,113 - DEBUG - VM ctest-pt_svm0-01442445 Tap interface: {'index': '7', 'name': 'tap9e9dd0e5-31', 'uuid': '9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524:ctest-right_vn-67801524', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '126.160.142.68', 'mac_addr': '02:9e:9d:d0:e5:31', '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-37450460:9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', 'admin_state': 'Enabled', 'flow_key_idx': '29', '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': '3938ee0e-440f-4526-a40e-92c693b93f08', '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': []} 2024-09-19 21:57:57,113 - DEBUG - Agent 10.0.0.28 vrf name: default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524:ctest-right_vn-67801524 2024-09-19 21:57:57,119 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524:ctest-right_vn-67801524', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.13:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2024-09-19 21:57:57,289 - DEBUG - VM ctest-pt_svm0-01442445 : Tap interface tap9e9dd0e5-31 is set to Active 2024-09-19 21:57:57,289 - DEBUG - Tap interface tap9e9dd0e5-31 detail : {'index': '7', 'name': 'tap9e9dd0e5-31', 'uuid': '9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', 'vrf_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524:ctest-right_vn-67801524', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524', 'vm_uuid': 'dffa271a-652e-4d26-816e-47c6a5a0c060', 'vm_name': 'ctest-pt_svm0-01442445', 'ip_addr': '126.160.142.68', 'mac_addr': '02:9e:9d:d0:e5:31', '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-37450460:9e9dd0e5-31f7-4515-a16e-aa5b165da7c7', 'sg_uuid_list': ['d6858ac3-347c-483a-8282-c9d9479a6f1b'], 'static_route_list': None, 'vm_project_uuid': '123a38ab-1048-4618-aed8-2fc46da55396', 'admin_state': 'Enabled', 'flow_key_idx': '29', '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': '3938ee0e-440f-4526-a40e-92c693b93f08', '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': []} 2024-09-19 21:57:57,488 - DEBUG - Starting Layer 2 verification in Agent 2024-09-19 21:57:57,501 - DEBUG - Layer 2 path is seen for VM MAC 02:9e:9d:d0:e5:31 in agent 10.0.0.28 2024-09-19 21:57:57,501 - DEBUG - Active layer 2 route in agent is present for VMI tap9e9dd0e5-31 2024-09-19 21:57:57,502 - DEBUG - L2 label(53) matches bw route table and interface table 2024-09-19 21:57:57,507 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 is found in Agent of node 10.0.0.28 2024-09-19 21:57:57,726 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 is consistent in agent 10.0.0.28 2024-09-19 21:57:57,726 - DEBUG - Route for VM IP ['126.160.142.68'] is consistent in agent 10.0.0.28 2024-09-19 21:57:57,726 - DEBUG - VN default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 verification for VM ctest-pt_svm0-01442445 in Agent 10.0.0.28 passed 2024-09-19 21:57:57,924 - DEBUG - Starting all layer 2 verification in agent 10.0.0.28 2024-09-19 21:57:57,937 - DEBUG - Route for VM MAC 02:9e:9d:d0:e5:31 is consistent in agent 10.0.0.28 2024-09-19 21:57:57,937 - INFO - VM ctest-pt_svm0-01442445 verifications in Compute nodes passed 2024-09-19 21:57:57,938 - DEBUG - The SVMs in the SI are : [] 2024-09-19 21:57:57,938 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:57:57,947 - DEBUG - Service VM for SI 'ctest-si-26316059' is launched 2024-09-19 21:57:57,947 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:57:57,953 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:57:57,967 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:57:58,048 - DEBUG - Requesting: http://10.0.0.28:8082/service-instance/8c526ca0-4d8e-4cee-bd12-878e98b3b388 2024-09-19 21:57:58,059 - DEBUG - SI ctest-si-26316059 has Port Tuple: ['ctest-port_tuple-50884453'] 2024-09-19 21:57:58,164 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-37450460:ctest-left_vn-97460600 2024-09-19 21:57:58,312 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-37450460:ctest-right_vn-67801524 2024-09-19 21:57:58,475 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,489 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,489 - INFO - Route to 126.160.142.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-2007-1, and SI ctest-si-26316059 is seen as the NH 2024-09-19 21:57:58,650 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,659 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,659 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,665 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,665 - INFO - Route to 193.40.156.131 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-2007-1, and SI ctest-si-26316059 is seen as the NH 2024-09-19 21:57:58,666 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,666 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2024-09-19 21:57:58,666 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 126.160.142.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.28, gateway password: c0ntrail123 2024-09-19 21:57:58,666 - DEBUG - ping -s 56 -c 3 -W 1 126.160.142.67 2024-09-19 21:58:02,616 - DEBUG - PING 126.160.142.67 (126.160.142.67): 56 data bytes 64 bytes from 126.160.142.67: seq=0 ttl=61 time=5.039 ms 64 bytes from 126.160.142.67: seq=1 ttl=61 time=1.630 ms 64 bytes from 126.160.142.67: seq=2 ttl=61 time=1.529 ms --- 126.160.142.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.529/2.732/5.039 ms 2024-09-19 21:58:02,616 - INFO - Ping to IP 126.160.142.67 from VM ctest-left_vm-53599747 passed 2024-09-19 21:58:02,617 - INFO - Dettaching policy ctest-policy-56199966 from vn ctest-right_vn-67801524 2024-09-19 21:58:02,844 - INFO - Dettaching policy ctest-policy-56199966 from vn ctest-left_vn-97460600 2024-09-19 21:58:03,128 - INFO - Deleted policy ctest-policy-56199966 2024-09-19 21:58:03,128 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-si-26316059'] 2024-09-19 21:58:03,238 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-37450460', 'ctest-si-26316059'] 2024-09-19 21:58:03,238 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:58:03,244 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:58:03,259 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:58:03,341 - DEBUG - Service instance ctest-si-26316059 removed from api server 2024-09-19 21:58:03,342 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/dffa271a-652e-4d26-816e-47c6a5a0c060 2024-09-19 21:58:03,350 - DEBUG - All Service VMs unlinked from SI ctest-si-26316059 2024-09-19 21:58:03,360 - DEBUG - 0 svc intances found in all projects. They are [] 2024-09-19 21:58:03,361 - DEBUG - 0 svc intances found in current project. They are [] 2024-09-19 21:58:03,408 - DEBUG - 0 svc intances found in all projects. They are [] 2024-09-19 21:58:03,408 - DEBUG - 0 svc intances found in current project. They are [] 2024-09-19 21:58:03,408 - INFO - Deleting VM ctest-pt_svm0-01442445 2024-09-19 21:58:03,481 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-43118750'] 2024-09-19 21:58:03,564 - INFO - Service template: ['default-domain', 'ctest-service_template_1-43118750'] deleted successfully. 2024-09-19 21:58:03,564 - INFO - Deleting VM ctest-right_vm-63750573 2024-09-19 21:58:03,638 - INFO - Deleting VM ctest-left_vm-53599747 2024-09-19 21:58:03,712 - INFO - Deleting VN ctest-right_vn-67801524 2024-09-19 21:58:03,767 - DEBUG - VN 7fd2d0bc-e844-4150-bf6c-6453e454a9d5 still in use: Unable to complete operation on network 7fd2d0bc-e844-4150-bf6c-6453e454a9d5. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-33056f90-754f-46c3-98f2-d18ea29b1bf7'] 2024-09-19 21:58:03,767 - WARNING - Deleting VN ctest-right_vn-67801524 failed..Will retry 2024-09-19 21:58:05,915 - DEBUG - Response for deleting network () 2024-09-19 21:58:05,915 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/c10b0351-3bbc-4949-869c-a49bf203efa3 2024-09-19 21:58:05,926 - DEBUG - Response Code: 404 2024-09-19 21:58:05,926 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:58:05,932 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:58:05,990 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:58:06,097 - INFO - Validated that VN ctest-right_vn-67801524 is not found in API Server 2024-09-19 21:58:06,108 - DEBUG - VN ctest-right_vn-67801524 is not present in Agent 10.0.0.28 2024-09-19 21:58:06,108 - INFO - Validated that VN ctest-right_vn-67801524 is not in any agent 2024-09-19 21:58:06,125 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-67801524 info 2024-09-19 21:58:06,126 - INFO - Deleting VN ctest-left_vn-97460600 2024-09-19 21:58:06,246 - DEBUG - Response for deleting network () 2024-09-19 21:58:06,247 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/26724327-b83e-4da2-baf7-4668f3d07900 2024-09-19 21:58:06,253 - DEBUG - Response Code: 404 2024-09-19 21:58:06,254 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:58:06,262 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:58:06,319 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:58:06,407 - INFO - Validated that VN ctest-left_vn-97460600 is not found in API Server 2024-09-19 21:58:06,419 - DEBUG - VN ctest-left_vn-97460600 is not present in Agent 10.0.0.28 2024-09-19 21:58:06,419 - INFO - Validated that VN ctest-left_vn-97460600 is not in any agent 2024-09-19 21:58:06,434 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-97460600 info 2024-09-19 21:58:06,435 - INFO - Deleting VN ctest-mgmt_vn-15527038 2024-09-19 21:58:06,556 - DEBUG - Response for deleting network () 2024-09-19 21:58:06,556 - DEBUG - Requesting: http://10.0.0.28:8082/routing-instance/0f322fbc-0569-45fb-b5b0-9825dd60d4f0 2024-09-19 21:58:06,563 - DEBUG - Response Code: 404 2024-09-19 21:58:06,563 - DEBUG - Requesting: http://10.0.0.28:8082/domains 2024-09-19 21:58:06,569 - DEBUG - Requesting: http://10.0.0.28:8082/domain/12c8fc9f-871f-460f-9e58-5706cfbc9a63 2024-09-19 21:58:06,623 - DEBUG - Requesting: http://10.0.0.28:8082/project/123a38ab-1048-4618-aed8-2fc46da55396 2024-09-19 21:58:06,704 - INFO - Validated that VN ctest-mgmt_vn-15527038 is not found in API Server 2024-09-19 21:58:06,716 - DEBUG - VN ctest-mgmt_vn-15527038 is not present in Agent 10.0.0.28 2024-09-19 21:58:06,716 - INFO - Validated that VN ctest-mgmt_vn-15527038 is not in any agent 2024-09-19 21:58:06,732 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-15527038 info 2024-09-19 21:58:06,986 - DEBUG - No XMPP flaps were noticed during the test 2024-09-19 21:58:06,987 - INFO -
2024-09-19 21:58:06,987 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:16]
2024-09-19 21:58:06,987 - INFO - -------------------------------------------------------------------------------- 2024-09-19 21:58:07,719 - INFO - Deleted project: ctest-TestSvcRegr-37450460, ID : 123a38ab-1048-4618-aed8-2fc46da55396