2025-03-24 21:11:45,592 - INFO - Domain Default found not creating 2025-03-24 21:11:45,766 - INFO - Project ctest-TestSvcRegr-28874923 not found, creating it 2025-03-24 21:11:46,256 - INFO - Created Project:ctest-TestSvcRegr-28874923, ID : 526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:48,165 - INFO - ================================================================================ 2025-03-24 21:11:48,165 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-03-24 21:11:48,424 - DEBUG - Nothing to compare xmpp stats {'10.0.0.17': {'10.20.0.19': '0'}} with 2025-03-24 21:11:48,424 - INFO - Initial checks done. Running the testcase now 2025-03-24 21:11:48,424 - INFO - 2025-03-24 21:11:49,023 - DEBUG - Response for create_network : {'network': {'id': '1c10b278-206e-4e55-a79f-1ec61887cad8', 'name': 'ctest-mgmt_vn-89880770', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'project_id': '526702ff190f4c58a484f97415146f55', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-mgmt_vn-89880770'], 'port_security_enabled': True, 'description': ''}} 2025-03-24 21:11:49,223 - DEBUG - Response for create_subnet : {'subnet': {'id': '67c92791-9327-48fa-9f45-381562831411', 'name': '', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'network_id': '1c10b278-206e-4e55-a79f-1ec61887cad8', 'ip_version': 4, 'cidr': '180.137.126.64/26', 'allocation_pools': [{'start': '180.137.126.66', 'end': '180.137.126.126'}], 'gateway_ip': '180.137.126.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '180.137.126.66', 'tags': [], 'project_id': '526702ff190f4c58a484f97415146f55'}} 2025-03-24 21:11:49,241 - INFO - Created VN ctest-mgmt_vn-89880770 2025-03-24 21:11:49,293 - DEBUG - VN ctest-mgmt_vn-89880770 UUID is 1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,408 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:49,698 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:49,729 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:49,745 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:49,821 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,835 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,848 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:11:49,856 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:11:49,866 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/a95e11b0-b241-400c-bf75-19b8cdfb6751 2025-03-24 21:11:49,876 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-03-24 21:11:49,876 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,889 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:11:49,907 - INFO - Verified VN network id 8 for VN 1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,907 - INFO - Verifications in API Server for VN ctest-mgmt_vn-89880770 passed 2025-03-24 21:11:49,907 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:49,918 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:11:49,926 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:11:49,934 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/a95e11b0-b241-400c-bf75-19b8cdfb6751 2025-03-24 21:11:49,948 - DEBUG - Control-node 10.0.0.17 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-28874923', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2022312461699272277', 'uuid-lslong': '12078406561794869976'}, 'enable': 'true', 'created': '2025-03-24T21:11:48', 'last-modified': '2025-03-24T21:11:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.756393'} 2025-03-24 21:11:49,955 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-03-24 21:11:49,955 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-89880770 passed 2025-03-24 21:11:49,955 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-89880770 in API_Server ====== 2025-03-24 21:11:49,955 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:49,962 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:49,977 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:50,060 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1c10b278-206e-4e55-a79f-1ec61887cad8 2025-03-24 21:11:50,074 - DEBUG - =>VN ctest-mgmt_vn-89880770 has no policy to be verified 2025-03-24 21:11:50,074 - DEBUG - Verifying the vn in opserver 2025-03-24 21:11:50,074 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 virtual network link through opserver 10.0.0.17 2025-03-24 21:11:50,074 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks 2025-03-24 21:11:50,380 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks 2025-03-24 21:11:50,446 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770', 'href': 'http://10.0.0.17:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770?flat'} 2025-03-24 21:11:50,446 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is found in opserver 2025-03-24 21:11:50,458 - DEBUG - Do not have enough data to verify VN in agent 2025-03-24 21:11:50,466 - DEBUG - VRF ids for VN ctest-mgmt_vn-89880770: {} 2025-03-24 21:11:50,661 - DEBUG - Response for create_network : {'network': {'id': '1224716e-57a7-4a70-9942-c6ac8baad9bd', 'name': 'ctest-left_vn-15531517', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'project_id': '526702ff190f4c58a484f97415146f55', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-left_vn-15531517'], 'port_security_enabled': True, 'description': ''}} 2025-03-24 21:11:50,855 - DEBUG - Response for create_subnet : {'subnet': {'id': '5d3b67b3-7fb7-4859-af7e-2b9c04478f12', 'name': '', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'network_id': '1224716e-57a7-4a70-9942-c6ac8baad9bd', 'ip_version': 4, 'cidr': '57.46.147.64/26', 'allocation_pools': [{'start': '57.46.147.66', 'end': '57.46.147.126'}], 'gateway_ip': '57.46.147.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '57.46.147.66', 'tags': [], 'project_id': '526702ff190f4c58a484f97415146f55'}} 2025-03-24 21:11:50,874 - INFO - Created VN ctest-left_vn-15531517 2025-03-24 21:11:50,929 - DEBUG - VN ctest-left_vn-15531517 UUID is 1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,044 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:51,050 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:51,062 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:51,143 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,156 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,169 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:11:51,180 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:11:51,190 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/aea5721c-aed1-4b16-bb9d-ac428a555667 2025-03-24 21:11:51,198 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-03-24 21:11:51,198 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,211 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:11:51,232 - INFO - Verified VN network id 9 for VN 1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,232 - INFO - Verifications in API Server for VN ctest-left_vn-15531517 passed 2025-03-24 21:11:51,232 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,243 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:11:51,252 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:11:51,262 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/aea5721c-aed1-4b16-bb9d-ac428a555667 2025-03-24 21:11:51,278 - DEBUG - Control-node 10.0.0.17 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-28874923', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1307294510575209072', 'uuid-lslong': '11043607680645781949'}, 'enable': 'true', 'created': '2025-03-24T21:11:50', 'last-modified': '2025-03-24T21:11:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.468134'} 2025-03-24 21:11:51,287 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-03-24 21:11:51,287 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-15531517 passed 2025-03-24 21:11:51,287 - DEBUG - ====Verifying policy data for ctest-left_vn-15531517 in API_Server ====== 2025-03-24 21:11:51,287 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:51,295 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:51,313 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:51,399 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/1224716e-57a7-4a70-9942-c6ac8baad9bd 2025-03-24 21:11:51,412 - DEBUG - =>VN ctest-left_vn-15531517 has no policy to be verified 2025-03-24 21:11:51,412 - DEBUG - Verifying the vn in opserver 2025-03-24 21:11:51,412 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 virtual network link through opserver 10.0.0.17 2025-03-24 21:11:51,412 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks 2025-03-24 21:11:51,424 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517', 'href': 'http://10.0.0.17:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517?flat'} 2025-03-24 21:11:51,424 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 is found in opserver 2025-03-24 21:11:51,437 - DEBUG - Do not have enough data to verify VN in agent 2025-03-24 21:11:51,443 - DEBUG - VRF ids for VN ctest-left_vn-15531517: {} 2025-03-24 21:11:51,641 - DEBUG - Response for create_network : {'network': {'id': '0728ce79-7de2-4e7a-adea-4abe62ab1ccb', 'name': 'ctest-right_vn-85972763', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'project_id': '526702ff190f4c58a484f97415146f55', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-right_vn-85972763'], 'port_security_enabled': True, 'description': ''}} 2025-03-24 21:11:51,844 - DEBUG - Response for create_subnet : {'subnet': {'id': 'de0917e8-327e-4e53-8f9e-b1aeafef51aa', 'name': '', 'tenant_id': '526702ff190f4c58a484f97415146f55', 'network_id': '0728ce79-7de2-4e7a-adea-4abe62ab1ccb', 'ip_version': 4, 'cidr': '218.61.166.64/26', 'allocation_pools': [{'start': '218.61.166.66', 'end': '218.61.166.126'}], 'gateway_ip': '218.61.166.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.61.166.66', 'tags': [], 'project_id': '526702ff190f4c58a484f97415146f55'}} 2025-03-24 21:11:51,863 - INFO - Created VN ctest-right_vn-85972763 2025-03-24 21:11:51,917 - DEBUG - VN ctest-right_vn-85972763 UUID is 0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,052 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:52,058 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:52,071 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:52,144 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,157 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,168 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:11:52,176 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:11:52,186 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/8d59a706-23d5-42d4-af33-b14520369359 2025-03-24 21:11:52,194 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-03-24 21:11:52,194 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,206 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:11:52,224 - INFO - Verified VN network id 10 for VN 0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,224 - INFO - Verifications in API Server for VN ctest-right_vn-85972763 passed 2025-03-24 21:11:52,224 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,236 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:11:52,246 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:11:52,256 - DEBUG - Requesting: http://10.0.0.17:8082/route-target/8d59a706-23d5-42d4-af33-b14520369359 2025-03-24 21:11:52,276 - DEBUG - Control-node 10.0.0.17 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-28874923', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '515889178532269690', 'uuid-lslong': '12531911094671449291'}, 'enable': 'true', 'created': '2025-03-24T21:11:51', 'last-modified': '2025-03-24T21:11:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466801'} 2025-03-24 21:11:52,285 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-03-24 21:11:52,285 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-85972763 passed 2025-03-24 21:11:52,285 - DEBUG - ====Verifying policy data for ctest-right_vn-85972763 in API_Server ====== 2025-03-24 21:11:52,286 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:11:52,293 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:11:52,308 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:11:52,390 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-network/0728ce79-7de2-4e7a-adea-4abe62ab1ccb 2025-03-24 21:11:52,401 - DEBUG - =>VN ctest-right_vn-85972763 has no policy to be verified 2025-03-24 21:11:52,401 - DEBUG - Verifying the vn in opserver 2025-03-24 21:11:52,401 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 virtual network link through opserver 10.0.0.17 2025-03-24 21:11:52,401 - DEBUG - Requesting: http://10.0.0.17:8081/analytics/uves/virtual-networks 2025-03-24 21:11:52,413 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763', 'href': 'http://10.0.0.17:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763?flat'} 2025-03-24 21:11:52,413 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 is found in opserver 2025-03-24 21:11:52,426 - DEBUG - Do not have enough data to verify VN in agent 2025-03-24 21:11:52,431 - DEBUG - VRF ids for VN ctest-right_vn-85972763: {} 2025-03-24 21:11:52,486 - DEBUG - Services list from nova: [, , ] 2025-03-24 21:11:53,595 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2945-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2945-1) 2025-03-24 21:11:54,821 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2945-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2945-1) 2025-03-24 21:11:54,822 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-16827954'] 2025-03-24 21:11:55,213 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16827954'] created succesfully 2025-03-24 21:11:55,594 - DEBUG - Image by name cirros_in_net not found 2025-03-24 21:11:55,595 - DEBUG - Installing image cirros_in_net 2025-03-24 21:11:55,595 - 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-03-24 21:11:55,595 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-03-24 21:11:55,595 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-03-24 21:11:55,608 - 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-03-24 21:11:55,792 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-03-24 21:11:55,792 - DEBUG - Unzip image 2025-03-24 21:11:55,792 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-03-24 21:11:55,952 - DEBUG - Try to create image in user context 2025-03-24 21:11:56,200 - DEBUG - Image has been created in user context 2025-03-24 21:11:56,200 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-03-24 21:11:56,215 - DEBUG - Downloaded image has been removed 2025-03-24 21:11:56,233 - DEBUG - Uploaded image status = active 2025-03-24 21:11:57,412 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2945-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2945-1) 2025-03-24 21:11:57,412 - INFO - Waiting for VM ctest-pt_svm0-75471073 to be up.. 2025-03-24 21:11:57,453 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-03-24 21:12:02,562 - DEBUG - VM is in ACTIVE state now 2025-03-24 21:12:02,562 - INFO - VM name : ctest-pt_svm0-75471073 2025-03-24 21:12:02,675 - DEBUG - VM ctest-pt_svm0-75471073 ID is ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:02,703 - DEBUG - VM ctest-pt_svm0-75471073 launched on Node cn-jenkins-deploy-platform-ansible-os-2945-1 2025-03-24 21:12:03,047 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:03,062 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/00791f78-4762-4c48-9f4f-22c727f7c542 2025-03-24 21:12:03,080 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/801da8a7-0d51-42d7-9686-0817ced16aac 2025-03-24 21:12:03,094 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/a8583b9b-2d90-4a30-8d9d-64ae4f3e2662 2025-03-24 21:12:06,417 - 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 1008ms') 2025-03-24 21:12:06,417 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-75471073 failed! 2025-03-24 21:12:06,481 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is 180.137.126.65 and allocation pool is NOT set 2025-03-24 21:12:10,580 - 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 1027ms') 2025-03-24 21:12:10,580 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-75471073 failed! 2025-03-24 21:12:10,641 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is 180.137.126.65 and allocation pool is NOT set 2025-03-24 21:12:14,731 - 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 1025ms') 2025-03-24 21:12:14,731 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-75471073 failed! 2025-03-24 21:12:14,797 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is 180.137.126.65 and allocation pool is NOT set 2025-03-24 21:12:18,897 - 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 1033ms') 2025-03-24 21:12:18,897 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-75471073 failed! 2025-03-24 21:12:18,953 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is 180.137.126.65 and allocation pool is NOT set 2025-03-24 21:12:21,015 - 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=5.74 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.961 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.961/3.352/5.743/2.391 ms') 2025-03-24 21:12:21,015 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-75471073 passed 2025-03-24 21:12:21,175 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:21,176 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:21,243 - 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-03-24 21:12:21,350 - DEBUG - VM ctest-pt_svm0-75471073 is NOT ready for SSH connections, VM status: ACTIVE 2025-03-24 21:12:26,351 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:26,352 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:26,420 - 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-03-24 21:12:26,518 - DEBUG - VM ctest-pt_svm0-75471073 is NOT ready for SSH connections, VM status: ACTIVE 2025-03-24 21:12:31,519 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:31,519 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:31,587 - 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-03-24 21:12:31,671 - DEBUG - VM ctest-pt_svm0-75471073 is NOT ready for SSH connections, VM status: ACTIVE 2025-03-24 21:12:36,671 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:36,671 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:36,843 - DEBUG - VM ctest-pt_svm0-75471073 is ready for SSH connections 2025-03-24 21:12:36,997 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:36,998 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:37,156 - DEBUG - VM ctest-pt_svm0-75471073 is ready for SSH connections 2025-03-24 21:12:37,309 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:37,309 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-75471073, IP 180.137.126.67, Port 22 2025-03-24 21:12:37,479 - DEBUG - VM ctest-pt_svm0-75471073 is ready for SSH connections 2025-03-24 21:12:37,486 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-si-46653681'] 2025-03-24 21:12:38,381 - INFO - Waiting for VM ctest-left_vm-12689856 to be up.. 2025-03-24 21:12:38,465 - DEBUG - VM is in ACTIVE state now 2025-03-24 21:12:38,465 - INFO - VM name : ctest-left_vm-12689856 2025-03-24 21:12:38,562 - DEBUG - VM ctest-left_vm-12689856 ID is 545c6164-27c4-43f9-a5e8-9f3450b42e3d 2025-03-24 21:12:38,562 - DEBUG - VM ctest-left_vm-12689856 launched on Node cn-jenkins-deploy-platform-ansible-os-2945-1 2025-03-24 21:12:38,640 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/545c6164-27c4-43f9-a5e8-9f3450b42e3d 2025-03-24 21:12:38,650 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/e37a5cde-4488-4886-b2cb-4045734234cd 2025-03-24 21:12:39,907 - 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.23 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.465 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.465/1.846/3.227/1.381 ms') 2025-03-24 21:12:39,907 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-12689856 passed 2025-03-24 21:12:40,066 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:40,066 - DEBUG - Waiting to SSH to VM ctest-left_vm-12689856, IP 57.46.147.67, Port 22 2025-03-24 21:12:40,232 - DEBUG - VM ctest-left_vm-12689856 is ready for SSH connections 2025-03-24 21:12:40,234 - INFO - Waiting for VM ctest-right_vm-65906258 to be up.. 2025-03-24 21:12:40,328 - DEBUG - VM is in ACTIVE state now 2025-03-24 21:12:40,328 - INFO - VM name : ctest-right_vm-65906258 2025-03-24 21:12:40,410 - DEBUG - VM ctest-right_vm-65906258 ID is d093e014-65ef-4de7-8501-f571a82081ba 2025-03-24 21:12:40,410 - DEBUG - VM ctest-right_vm-65906258 launched on Node cn-jenkins-deploy-platform-ansible-os-2945-1 2025-03-24 21:12:40,492 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/d093e014-65ef-4de7-8501-f571a82081ba 2025-03-24 21:12:40,502 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/63d06c77-942a-4989-833f-abb92d66a338 2025-03-24 21:12:41,750 - 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.514 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.514/1.743/2.972/1.229 ms') 2025-03-24 21:12:41,750 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-65906258 passed 2025-03-24 21:12:41,913 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:41,913 - DEBUG - Waiting to SSH to VM ctest-right_vm-65906258, IP 218.61.166.67, Port 22 2025-03-24 21:12:42,069 - DEBUG - VM ctest-right_vm-65906258 is ready for SSH connections 2025-03-24 21:12:42,076 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517, 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-28874923:ctest-right_vn-85972763, 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-28874923:ctest-si-46653681'], 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-03-24 21:12:42,525 - INFO - Attaching policy ctest-policy-40828016 to vn ctest-left_vn-15531517 2025-03-24 21:12:43,101 - INFO - Attaching policy ctest-policy-40828016 to vn ctest-right_vn-85972763 2025-03-24 21:12:43,561 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16827954'] created succesfully 2025-03-24 21:12:43,789 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-si-46653681'] created succesfully 2025-03-24 21:12:43,789 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:43,795 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:43,813 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:43,895 - DEBUG - Requesting: http://10.0.0.17:8082/service-instance/b8d9a21d-9dad-4218-ba83-2cb753dd8e6b 2025-03-24 21:12:43,907 - DEBUG - SI 'ctest-si-46653681' has service template ref to ctest-service_template_1-16827954 2025-03-24 21:12:43,907 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:43,913 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:43,927 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:44,016 - DEBUG - Requesting: http://10.0.0.17:8082/service-instance/b8d9a21d-9dad-4218-ba83-2cb753dd8e6b 2025-03-24 21:12:44,027 - DEBUG - Requesting: http://10.0.0.17:8082/port-tuple/dffd7f83-0435-492b-b83c-38892c722045 2025-03-24 21:12:44,037 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/00791f78-4762-4c48-9f4f-22c727f7c542 2025-03-24 21:12:44,052 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/801da8a7-0d51-42d7-9686-0817ced16aac 2025-03-24 21:12:44,066 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/a8583b9b-2d90-4a30-8d9d-64ae4f3e2662 2025-03-24 21:12:44,341 - DEBUG - VM is in ACTIVE state now 2025-03-24 21:12:44,821 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:44,834 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/00791f78-4762-4c48-9f4f-22c727f7c542 2025-03-24 21:12:44,848 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/801da8a7-0d51-42d7-9686-0817ced16aac 2025-03-24 21:12:44,861 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/a8583b9b-2d90-4a30-8d9d-64ae4f3e2662 2025-03-24 21:12:44,872 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/268a1c9f-c09b-40b2-b41e-0e40055368f3 2025-03-24 21:12:44,882 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/c8fae5c4-876e-40d4-a40a-5ce1356b2da6 2025-03-24 21:12:44,891 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/f161fce6-3e78-4384-b830-5f0148480756 2025-03-24 21:12:44,901 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/25465396-94af-460f-b23c-513a5235f850 2025-03-24 21:12:44,913 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/8d161359-7769-460a-8897-6fbf6bd05559 2025-03-24 21:12:44,923 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/956b76ec-530c-40f2-9cea-b20fac0be25d 2025-03-24 21:12:44,933 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/5de2f574-7f90-4afd-915e-17c047937aa0 2025-03-24 21:12:44,944 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/758dd09b-ebfb-4be4-9079-643b75471d13 2025-03-24 21:12:44,953 - DEBUG - Requesting: http://10.0.0.17:8082/instance-ip/c98a3b31-ca6e-40fd-b9dc-54b101623607 2025-03-24 21:12:45,462 - DEBUG - VM is in ACTIVE state now 2025-03-24 21:12:45,462 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:45,471 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/00791f78-4762-4c48-9f4f-22c727f7c542 2025-03-24 21:12:45,485 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/801da8a7-0d51-42d7-9686-0817ced16aac 2025-03-24 21:12:45,503 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine-interface/a8583b9b-2d90-4a30-8d9d-64ae4f3e2662 2025-03-24 21:12:46,764 - 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.87 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.497 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.497/1.183/1.869/0.686 ms') 2025-03-24 21:12:46,764 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-75471073 passed 2025-03-24 21:12:46,810 - DEBUG - VM ctest-pt_svm0-75471073 Tap interface: {'index': '6', 'name': 'tapa8583b9b-2d', 'uuid': 'a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517:ctest-left_vn-15531517', '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-28874923:ctest-left_vn-15531517', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '57.46.147.68', 'mac_addr': '02:a8:58:3b:9b:2d', '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-28874923:a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', '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': '801da8a7-0d51-42d7-9686-0817ced16aac', '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-03-24 21:12:46,810 - DEBUG - Agent 10.0.0.17 vrf name: default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517:ctest-left_vn-15531517 2025-03-24 21:12:46,817 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517:ctest-left_vn-15531517', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.19:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-03-24 21:12:46,992 - DEBUG - VM ctest-pt_svm0-75471073 : Tap interface tapa8583b9b-2d is set to Active 2025-03-24 21:12:46,992 - DEBUG - Tap interface tapa8583b9b-2d detail : {'index': '6', 'name': 'tapa8583b9b-2d', 'uuid': 'a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517:ctest-left_vn-15531517', '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-28874923:ctest-left_vn-15531517', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '57.46.147.68', 'mac_addr': '02:a8:58:3b:9b:2d', '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-28874923:a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', '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': '801da8a7-0d51-42d7-9686-0817ced16aac', '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-03-24 21:12:47,189 - DEBUG - Starting Layer 2 verification in Agent 2025-03-24 21:12:47,201 - DEBUG - Layer 2 path is seen for VM MAC 02:a8:58:3b:9b:2d in agent 10.0.0.17 2025-03-24 21:12:47,201 - DEBUG - Active layer 2 route in agent is present for VMI tapa8583b9b-2d 2025-03-24 21:12:47,201 - DEBUG - L2 label(48) matches bw route table and interface table 2025-03-24 21:12:47,207 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 is found in Agent of node 10.0.0.17 2025-03-24 21:12:47,423 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 is consistent in agent 10.0.0.17 2025-03-24 21:12:47,423 - DEBUG - Route for VM IP ['57.46.147.68'] is consistent in agent 10.0.0.17 2025-03-24 21:12:47,423 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 verification for VM ctest-pt_svm0-75471073 in Agent 10.0.0.17 passed 2025-03-24 21:12:47,617 - DEBUG - Starting all layer 2 verification in agent 10.0.0.17 2025-03-24 21:12:47,630 - DEBUG - Route for VM MAC 02:a8:58:3b:9b:2d is consistent in agent 10.0.0.17 2025-03-24 21:12:47,651 - DEBUG - VM ctest-pt_svm0-75471073 Tap interface: {'index': '5', 'name': 'tap00791f78-47', 'uuid': '00791f78-4762-4c48-9f4f-22c727f7c542', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770:ctest-mgmt_vn-89880770', '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-28874923:ctest-mgmt_vn-89880770', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '180.137.126.67', 'mac_addr': '02:00:79:1f:78:47', '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-28874923:00791f78-4762-4c48-9f4f-22c727f7c542', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', '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-03-24 21:12:47,651 - DEBUG - Agent 10.0.0.17 vrf name: default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770:ctest-mgmt_vn-89880770 2025-03-24 21:12:47,657 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770:ctest-mgmt_vn-89880770', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770', 'table_label': '-1', 'vxlan_id': '8', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.19:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-03-24 21:12:47,817 - DEBUG - VM ctest-pt_svm0-75471073 : Tap interface tap00791f78-47 is set to Active 2025-03-24 21:12:47,817 - DEBUG - Tap interface tap00791f78-47 detail : {'index': '5', 'name': 'tap00791f78-47', 'uuid': '00791f78-4762-4c48-9f4f-22c727f7c542', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770:ctest-mgmt_vn-89880770', '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-28874923:ctest-mgmt_vn-89880770', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '180.137.126.67', 'mac_addr': '02:00:79:1f:78:47', '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-28874923:00791f78-4762-4c48-9f4f-22c727f7c542', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', '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-03-24 21:12:48,017 - DEBUG - Starting Layer 2 verification in Agent 2025-03-24 21:12:48,032 - DEBUG - Layer 2 path is seen for VM MAC 02:00:79:1f:78:47 in agent 10.0.0.17 2025-03-24 21:12:48,033 - DEBUG - Active layer 2 route in agent is present for VMI tap00791f78-47 2025-03-24 21:12:48,033 - DEBUG - L2 label(43) matches bw route table and interface table 2025-03-24 21:12:48,040 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is found in Agent of node 10.0.0.17 2025-03-24 21:12:48,253 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 is consistent in agent 10.0.0.17 2025-03-24 21:12:48,253 - DEBUG - Route for VM IP ['180.137.126.67'] is consistent in agent 10.0.0.17 2025-03-24 21:12:48,253 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-mgmt_vn-89880770 verification for VM ctest-pt_svm0-75471073 in Agent 10.0.0.17 passed 2025-03-24 21:12:48,449 - DEBUG - Starting all layer 2 verification in agent 10.0.0.17 2025-03-24 21:12:48,462 - DEBUG - Route for VM MAC 02:00:79:1f:78:47 is consistent in agent 10.0.0.17 2025-03-24 21:12:48,486 - DEBUG - VM ctest-pt_svm0-75471073 Tap interface: {'index': '7', 'name': 'tap801da8a7-0d', 'uuid': '801da8a7-0d51-42d7-9686-0817ced16aac', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763:ctest-right_vn-85972763', '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-28874923:ctest-right_vn-85972763', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '218.61.166.68', 'mac_addr': '02:80:1d:a8:a7:0d', '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-28874923:801da8a7-0d51-42d7-9686-0817ced16aac', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', 'admin_state': 'Enabled', 'flow_key_idx': '56', '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': 'a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', '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-03-24 21:12:48,486 - DEBUG - Agent 10.0.0.17 vrf name: default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763:ctest-right_vn-85972763 2025-03-24 21:12:48,492 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763:ctest-right_vn-85972763', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.19:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-03-24 21:12:48,659 - DEBUG - VM ctest-pt_svm0-75471073 : Tap interface tap801da8a7-0d is set to Active 2025-03-24 21:12:48,659 - DEBUG - Tap interface tap801da8a7-0d detail : {'index': '7', 'name': 'tap801da8a7-0d', 'uuid': '801da8a7-0d51-42d7-9686-0817ced16aac', 'vrf_name': 'default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763:ctest-right_vn-85972763', '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-28874923:ctest-right_vn-85972763', 'vm_uuid': 'ce6c49e8-69b4-49eb-b356-e617e85eb1a3', 'vm_name': 'ctest-pt_svm0-75471073', 'ip_addr': '218.61.166.68', 'mac_addr': '02:80:1d:a8:a7:0d', '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-28874923:801da8a7-0d51-42d7-9686-0817ced16aac', 'sg_uuid_list': ['0b406768-695c-49ca-b549-58b3a79ffbf4'], 'static_route_list': None, 'vm_project_uuid': '526702ff-190f-4c58-a484-f97415146f55', 'admin_state': 'Enabled', 'flow_key_idx': '56', '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': 'a8583b9b-2d90-4a30-8d9d-64ae4f3e2662', '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-03-24 21:12:48,861 - DEBUG - Starting Layer 2 verification in Agent 2025-03-24 21:12:48,876 - DEBUG - Layer 2 path is seen for VM MAC 02:80:1d:a8:a7:0d in agent 10.0.0.17 2025-03-24 21:12:48,876 - DEBUG - Active layer 2 route in agent is present for VMI tap801da8a7-0d 2025-03-24 21:12:48,876 - DEBUG - L2 label(53) matches bw route table and interface table 2025-03-24 21:12:48,882 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 is found in Agent of node 10.0.0.17 2025-03-24 21:12:49,102 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 is consistent in agent 10.0.0.17 2025-03-24 21:12:49,103 - DEBUG - Route for VM IP ['218.61.166.68'] is consistent in agent 10.0.0.17 2025-03-24 21:12:49,103 - DEBUG - VN default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 verification for VM ctest-pt_svm0-75471073 in Agent 10.0.0.17 passed 2025-03-24 21:12:49,302 - DEBUG - Starting all layer 2 verification in agent 10.0.0.17 2025-03-24 21:12:49,314 - DEBUG - Route for VM MAC 02:80:1d:a8:a7:0d is consistent in agent 10.0.0.17 2025-03-24 21:12:49,314 - INFO - VM ctest-pt_svm0-75471073 verifications in Compute nodes passed 2025-03-24 21:12:49,315 - DEBUG - The SVMs in the SI are : [] 2025-03-24 21:12:49,315 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:49,323 - DEBUG - Service VM for SI 'ctest-si-46653681' is launched 2025-03-24 21:12:49,323 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:49,329 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:49,342 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:49,422 - DEBUG - Requesting: http://10.0.0.17:8082/service-instance/b8d9a21d-9dad-4218-ba83-2cb753dd8e6b 2025-03-24 21:12:49,435 - DEBUG - SI ctest-si-46653681 has Port Tuple: ['ctest-port_tuple-54220475'] 2025-03-24 21:12:49,553 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-28874923:ctest-left_vn-15531517 2025-03-24 21:12:49,705 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-28874923:ctest-right_vn-85972763 2025-03-24 21:12:49,862 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:49,875 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:49,875 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:49,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:49,883 - INFO - Route to 218.61.166.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-2945-1, and SI ctest-si-46653681 is seen as the NH 2025-03-24 21:12:50,047 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,057 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,057 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,064 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,064 - INFO - Route to 57.46.147.67 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-2945-1, and SI ctest-si-46653681 is seen as the NH 2025-03-24 21:12:50,064 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,064 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-03-24 21:12:50,065 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 218.61.166.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.17, gateway password: c0ntrail123 2025-03-24 21:12:50,065 - DEBUG - ping -s 56 -c 3 -W 1 218.61.166.67 2025-03-24 21:12:54,042 - DEBUG - PING 218.61.166.67 (218.61.166.67): 56 data bytes 64 bytes from 218.61.166.67: seq=0 ttl=61 time=5.999 ms 64 bytes from 218.61.166.67: seq=1 ttl=61 time=2.077 ms 64 bytes from 218.61.166.67: seq=2 ttl=61 time=2.144 ms --- 218.61.166.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 2.077/3.406/5.999 ms 2025-03-24 21:12:54,042 - INFO - Ping to IP 218.61.166.67 from VM ctest-left_vm-12689856 passed 2025-03-24 21:12:54,042 - INFO - Dettaching policy ctest-policy-40828016 from vn ctest-right_vn-85972763 2025-03-24 21:12:54,221 - INFO - Dettaching policy ctest-policy-40828016 from vn ctest-left_vn-15531517 2025-03-24 21:12:54,505 - INFO - Deleted policy ctest-policy-40828016 2025-03-24 21:12:54,506 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-si-46653681'] 2025-03-24 21:12:54,615 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-28874923', 'ctest-si-46653681'] 2025-03-24 21:12:54,615 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:54,624 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:54,639 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:54,721 - DEBUG - Service instance ctest-si-46653681 removed from api server 2025-03-24 21:12:54,721 - DEBUG - Requesting: http://10.0.0.17:8082/virtual-machine/ce6c49e8-69b4-49eb-b356-e617e85eb1a3 2025-03-24 21:12:54,732 - DEBUG - All Service VMs unlinked from SI ctest-si-46653681 2025-03-24 21:12:54,740 - DEBUG - 0 svc intances found in all projects. They are [] 2025-03-24 21:12:54,740 - DEBUG - 0 svc intances found in current project. They are [] 2025-03-24 21:12:54,793 - DEBUG - 0 svc intances found in all projects. They are [] 2025-03-24 21:12:54,793 - DEBUG - 0 svc intances found in current project. They are [] 2025-03-24 21:12:54,794 - INFO - Deleting VM ctest-pt_svm0-75471073 2025-03-24 21:12:54,869 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-16827954'] 2025-03-24 21:12:54,965 - INFO - Service template: ['default-domain', 'ctest-service_template_1-16827954'] deleted successfully. 2025-03-24 21:12:54,965 - INFO - Deleting VM ctest-right_vm-65906258 2025-03-24 21:12:55,050 - INFO - Deleting VM ctest-left_vm-12689856 2025-03-24 21:12:55,137 - INFO - Deleting VN ctest-right_vn-85972763 2025-03-24 21:12:55,176 - DEBUG - VN 0728ce79-7de2-4e7a-adea-4abe62ab1ccb still in use: Unable to complete operation on network 0728ce79-7de2-4e7a-adea-4abe62ab1ccb. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0692d573-5398-4c38-8449-91b87d2bab51'] 2025-03-24 21:12:55,176 - WARNING - Deleting VN ctest-right_vn-85972763 failed..Will retry 2025-03-24 21:12:57,284 - DEBUG - Response for deleting network () 2025-03-24 21:12:57,284 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/42f4deed-8eff-4d89-b60d-4658f7619222 2025-03-24 21:12:57,296 - DEBUG - Response Code: 404 2025-03-24 21:12:57,296 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:57,302 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:57,356 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:57,429 - INFO - Validated that VN ctest-right_vn-85972763 is not found in API Server 2025-03-24 21:12:57,439 - DEBUG - VN ctest-right_vn-85972763 is not present in Agent 10.0.0.17 2025-03-24 21:12:57,439 - INFO - Validated that VN ctest-right_vn-85972763 is not in any agent 2025-03-24 21:12:57,454 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-85972763 info 2025-03-24 21:12:57,454 - INFO - Deleting VN ctest-left_vn-15531517 2025-03-24 21:12:57,554 - DEBUG - Response for deleting network () 2025-03-24 21:12:57,555 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/7c00e4a6-f9e5-4254-a6f8-e6d960f72d01 2025-03-24 21:12:57,559 - DEBUG - Response Code: 404 2025-03-24 21:12:57,560 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:57,568 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:57,622 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:57,695 - INFO - Validated that VN ctest-left_vn-15531517 is not found in API Server 2025-03-24 21:12:57,706 - DEBUG - VN ctest-left_vn-15531517 is not present in Agent 10.0.0.17 2025-03-24 21:12:57,706 - INFO - Validated that VN ctest-left_vn-15531517 is not in any agent 2025-03-24 21:12:57,722 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-15531517 info 2025-03-24 21:12:57,722 - INFO - Deleting VN ctest-mgmt_vn-89880770 2025-03-24 21:12:57,826 - DEBUG - Response for deleting network () 2025-03-24 21:12:57,826 - DEBUG - Requesting: http://10.0.0.17:8082/routing-instance/97d9b1f9-b292-41b1-8cc8-0cfe8ccb2566 2025-03-24 21:12:57,833 - DEBUG - Response Code: 404 2025-03-24 21:12:57,833 - DEBUG - Requesting: http://10.0.0.17:8082/domains 2025-03-24 21:12:57,839 - DEBUG - Requesting: http://10.0.0.17:8082/domain/c032f704-8960-40dc-b550-8cfb6ee34d1c 2025-03-24 21:12:57,891 - DEBUG - Requesting: http://10.0.0.17:8082/project/526702ff-190f-4c58-a484-f97415146f55 2025-03-24 21:12:57,964 - INFO - Validated that VN ctest-mgmt_vn-89880770 is not found in API Server 2025-03-24 21:12:57,974 - DEBUG - VN ctest-mgmt_vn-89880770 is not present in Agent 10.0.0.17 2025-03-24 21:12:57,974 - INFO - Validated that VN ctest-mgmt_vn-89880770 is not in any agent 2025-03-24 21:12:57,990 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-89880770 info 2025-03-24 21:12:58,243 - DEBUG - No XMPP flaps were noticed during the test 2025-03-24 21:12:58,243 - INFO -
2025-03-24 21:12:58,243 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:10]
2025-03-24 21:12:58,243 - INFO - -------------------------------------------------------------------------------- 2025-03-24 21:12:58,941 - INFO - Deleted project: ctest-TestSvcRegr-28874923, ID : 526702ff-190f-4c58-a484-f97415146f55