2025-04-28 02:07:17,905 - INFO - Domain Default found not creating 2025-04-28 02:07:18,024 - INFO - Project ctest-TestSvcRegr-46385884 not found, creating it 2025-04-28 02:07:18,448 - INFO - Created Project:ctest-TestSvcRegr-46385884, ID : 2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:19,774 - INFO - ================================================================================ 2025-04-28 02:07:19,774 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-04-28 02:07:20,027 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.209': '0'}} with 2025-04-28 02:07:20,027 - INFO - Initial checks done. Running the testcase now 2025-04-28 02:07:20,027 - INFO - 2025-04-28 02:07:20,550 - DEBUG - Response for create_network : {'network': {'id': 'c3cb17d0-ef5f-4dfa-8bdc-9c445151e739', 'name': 'ctest-mgmt_vn-91118577', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-mgmt_vn-91118577'], 'port_security_enabled': True, 'description': ''}} 2025-04-28 02:07:20,714 - DEBUG - Response for create_subnet : {'subnet': {'id': 'aa1c5811-12f4-4985-bfa8-f2b35c05a028', 'name': '', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'network_id': 'c3cb17d0-ef5f-4dfa-8bdc-9c445151e739', 'ip_version': 4, 'cidr': '180.221.246.64/26', 'allocation_pools': [{'start': '180.221.246.66', 'end': '180.221.246.126'}], 'gateway_ip': '180.221.246.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '180.221.246.66', 'tags': [], 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99'}} 2025-04-28 02:07:20,729 - INFO - Created VN ctest-mgmt_vn-91118577 2025-04-28 02:07:20,781 - DEBUG - VN ctest-mgmt_vn-91118577 UUID is c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:20,877 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:21,164 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:21,188 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:21,202 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:21,279 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,289 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,298 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:07:21,305 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:07:21,318 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/98c0afe4-70a5-460f-86b6-576e511caeb6 2025-04-28 02:07:21,325 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-28 02:07:21,325 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,334 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:07:21,348 - INFO - Verified VN network id 8 for VN c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,348 - INFO - Verifications in API Server for VN ctest-mgmt_vn-91118577 passed 2025-04-28 02:07:21,348 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,356 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:07:21,363 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:07:21,369 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/98c0afe4-70a5-460f-86b6-576e511caeb6 2025-04-28 02:07:21,380 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46385884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14108396443804847610', 'uuid-lslong': '10078101883384358713'}, 'enable': 'true', 'created': '2025-04-28T02:07:20', 'last-modified': '2025-04-28T02:07:20', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.690555'} 2025-04-28 02:07:21,386 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-04-28 02:07:21,386 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-91118577 passed 2025-04-28 02:07:21,386 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-91118577 in API_Server ====== 2025-04-28 02:07:21,387 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:21,392 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:21,403 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:21,475 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/c3cb17d0-ef5f-4dfa-8bdc-9c445151e739 2025-04-28 02:07:21,486 - DEBUG - =>VN ctest-mgmt_vn-91118577 has no policy to be verified 2025-04-28 02:07:21,486 - DEBUG - Verifying the vn in opserver 2025-04-28 02:07:21,486 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 virtual network link through opserver 10.0.0.50 2025-04-28 02:07:21,486 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2025-04-28 02:07:21,774 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2025-04-28 02:07:21,822 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577?flat'} 2025-04-28 02:07:21,822 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is found in opserver 2025-04-28 02:07:21,833 - DEBUG - Do not have enough data to verify VN in agent 2025-04-28 02:07:21,838 - DEBUG - VRF ids for VN ctest-mgmt_vn-91118577: {} 2025-04-28 02:07:22,012 - DEBUG - Response for create_network : {'network': {'id': 'd81331a5-4f7e-4c54-8b4e-04fd56502416', 'name': 'ctest-left_vn-13580219', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-left_vn-13580219'], 'port_security_enabled': True, 'description': ''}} 2025-04-28 02:07:22,218 - DEBUG - Response for create_subnet : {'subnet': {'id': '2661add3-1672-4e30-a31b-5b538a739e95', 'name': '', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'network_id': 'd81331a5-4f7e-4c54-8b4e-04fd56502416', 'ip_version': 4, 'cidr': '208.16.30.0/26', 'allocation_pools': [{'start': '208.16.30.2', 'end': '208.16.30.62'}], 'gateway_ip': '208.16.30.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '208.16.30.2', 'tags': [], 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99'}} 2025-04-28 02:07:22,235 - INFO - Created VN ctest-left_vn-13580219 2025-04-28 02:07:22,285 - DEBUG - VN ctest-left_vn-13580219 UUID is d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,387 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:22,391 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:22,402 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:22,471 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,481 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,488 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:07:22,494 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:07:22,500 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/fe419bd4-3e3f-4a28-9e48-2c13abd50880 2025-04-28 02:07:22,505 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-28 02:07:22,505 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,512 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:07:22,524 - INFO - Verified VN network id 9 for VN d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,524 - INFO - Verifications in API Server for VN ctest-left_vn-13580219 passed 2025-04-28 02:07:22,524 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,532 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:07:22,537 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:07:22,543 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/fe419bd4-3e3f-4a28-9e48-2c13abd50880 2025-04-28 02:07:22,553 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46385884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15569842922822978644', 'uuid-lslong': '10037966105576743958'}, 'enable': 'true', 'created': '2025-04-28T02:07:21', 'last-modified': '2025-04-28T02:07:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.363969'} 2025-04-28 02:07:22,559 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-04-28 02:07:22,559 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-13580219 passed 2025-04-28 02:07:22,559 - DEBUG - ====Verifying policy data for ctest-left_vn-13580219 in API_Server ====== 2025-04-28 02:07:22,559 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:22,564 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:22,574 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:22,647 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/d81331a5-4f7e-4c54-8b4e-04fd56502416 2025-04-28 02:07:22,656 - DEBUG - =>VN ctest-left_vn-13580219 has no policy to be verified 2025-04-28 02:07:22,656 - DEBUG - Verifying the vn in opserver 2025-04-28 02:07:22,656 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 virtual network link through opserver 10.0.0.50 2025-04-28 02:07:22,656 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2025-04-28 02:07:22,665 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219?flat'} 2025-04-28 02:07:22,665 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 is found in opserver 2025-04-28 02:07:22,675 - DEBUG - Do not have enough data to verify VN in agent 2025-04-28 02:07:22,679 - DEBUG - VRF ids for VN ctest-left_vn-13580219: {} 2025-04-28 02:07:22,835 - DEBUG - Response for create_network : {'network': {'id': '2a8970d1-faa6-4dfa-a756-da0bc64d0fb4', 'name': 'ctest-right_vn-59189569', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-right_vn-59189569'], 'port_security_enabled': True, 'description': ''}} 2025-04-28 02:07:22,984 - DEBUG - Response for create_subnet : {'subnet': {'id': '690d15ef-c889-4bb3-ba0c-95920b37425f', 'name': '', 'tenant_id': '2d2aa3e2c4264c5985e6ee56931afd99', 'network_id': '2a8970d1-faa6-4dfa-a756-da0bc64d0fb4', 'ip_version': 4, 'cidr': '201.13.71.64/26', 'allocation_pools': [{'start': '201.13.71.66', 'end': '201.13.71.126'}], 'gateway_ip': '201.13.71.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.13.71.66', 'tags': [], 'project_id': '2d2aa3e2c4264c5985e6ee56931afd99'}} 2025-04-28 02:07:23,000 - INFO - Created VN ctest-right_vn-59189569 2025-04-28 02:07:23,053 - DEBUG - VN ctest-right_vn-59189569 UUID is 2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,159 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:23,165 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:23,176 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:23,250 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,260 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,269 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:07:23,276 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:07:23,282 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/75487cad-6d4f-4528-bfff-f16877cdb9e2 2025-04-28 02:07:23,288 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-04-28 02:07:23,288 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,296 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:07:23,308 - INFO - Verified VN network id 10 for VN 2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,308 - INFO - Verifications in API Server for VN ctest-right_vn-59189569 passed 2025-04-28 02:07:23,308 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,316 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:07:23,322 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:07:23,328 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/75487cad-6d4f-4528-bfff-f16877cdb9e2 2025-04-28 02:07:23,339 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46385884', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3065105068558011898', 'uuid-lslong': '12058064796437516212'}, 'enable': 'true', 'created': '2025-04-28T02:07:22', 'last-modified': '2025-04-28T02:07:22', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380788'} 2025-04-28 02:07:23,345 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-04-28 02:07:23,345 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-59189569 passed 2025-04-28 02:07:23,345 - DEBUG - ====Verifying policy data for ctest-right_vn-59189569 in API_Server ====== 2025-04-28 02:07:23,345 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:07:23,350 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:07:23,361 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:07:23,429 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 2025-04-28 02:07:23,438 - DEBUG - =>VN ctest-right_vn-59189569 has no policy to be verified 2025-04-28 02:07:23,438 - DEBUG - Verifying the vn in opserver 2025-04-28 02:07:23,438 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 virtual network link through opserver 10.0.0.50 2025-04-28 02:07:23,438 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2025-04-28 02:07:23,447 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569?flat'} 2025-04-28 02:07:23,447 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 is found in opserver 2025-04-28 02:07:23,456 - DEBUG - Do not have enough data to verify VN in agent 2025-04-28 02:07:23,461 - DEBUG - VRF ids for VN ctest-right_vn-59189569: {} 2025-04-28 02:07:23,508 - DEBUG - Services list from nova: [, , ] 2025-04-28 02:07:24,449 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3232-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3232-1) 2025-04-28 02:07:25,565 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3232-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3232-1) 2025-04-28 02:07:25,566 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-90519722'] 2025-04-28 02:07:25,937 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-90519722'] created succesfully 2025-04-28 02:07:26,279 - DEBUG - Image by name cirros_in_net not found 2025-04-28 02:07:26,279 - DEBUG - Installing image cirros_in_net 2025-04-28 02:07:26,279 - 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-04-28 02:07:26,279 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-04-28 02:07:26,279 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-04-28 02:07:26,289 - 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-04-28 02:07:26,381 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-04-28 02:07:26,381 - DEBUG - Unzip image 2025-04-28 02:07:26,381 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-04-28 02:07:26,530 - DEBUG - Try to create image in user context 2025-04-28 02:07:26,728 - DEBUG - Image has been created in user context 2025-04-28 02:07:26,728 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-04-28 02:07:26,740 - DEBUG - Downloaded image has been removed 2025-04-28 02:07:26,752 - DEBUG - Uploaded image status = active 2025-04-28 02:07:27,789 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3232-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3232-1) 2025-04-28 02:07:27,789 - INFO - Waiting for VM ctest-pt_svm0-47341098 to be up.. 2025-04-28 02:07:27,823 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-04-28 02:07:32,910 - DEBUG - VM is in ACTIVE state now 2025-04-28 02:07:32,910 - INFO - VM name : ctest-pt_svm0-47341098 2025-04-28 02:07:32,986 - DEBUG - VM ctest-pt_svm0-47341098 ID is e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:07:33,005 - DEBUG - VM ctest-pt_svm0-47341098 launched on Node cn-jenkins-deploy-platform-ansible-os-3232-1 2025-04-28 02:07:33,260 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:07:33,269 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/0f39bbcf-10dd-4f21-a811-8b1e1eb7859b 2025-04-28 02:07:33,278 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ca89dc02-8b9c-4f65-840b-2a9709295c13 2025-04-28 02:07:33,290 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/d0545d4d-34b1-407b-a9ab-a1960e6de08e 2025-04-28 02:07:36,594 - 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 1004ms') 2025-04-28 02:07:36,595 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-47341098 failed! 2025-04-28 02:07:36,649 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is 180.221.246.65 and allocation pool is NOT set 2025-04-28 02:07:40,714 - 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 1012ms') 2025-04-28 02:07:40,714 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-47341098 failed! 2025-04-28 02:07:40,769 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is 180.221.246.65 and allocation pool is NOT set 2025-04-28 02:07:44,847 - 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 1020ms') 2025-04-28 02:07:44,847 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-47341098 failed! 2025-04-28 02:07:44,901 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is 180.221.246.65 and allocation pool is NOT set 2025-04-28 02:07:46,970 - 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=6.23 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=4.98 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 = 4.978/5.604/6.231/0.626 ms') 2025-04-28 02:07:46,970 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-47341098 passed 2025-04-28 02:07:47,125 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:07:47,125 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:07:47,180 - 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-04-28 02:07:47,252 - DEBUG - VM ctest-pt_svm0-47341098 is NOT ready for SSH connections, VM status: ACTIVE 2025-04-28 02:07:52,253 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:07:52,253 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:07:52,309 - 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-04-28 02:07:52,397 - DEBUG - VM ctest-pt_svm0-47341098 is NOT ready for SSH connections, VM status: ACTIVE 2025-04-28 02:07:57,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:07:57,398 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:07:57,452 - 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-04-28 02:07:57,525 - DEBUG - VM ctest-pt_svm0-47341098 is NOT ready for SSH connections, VM status: ACTIVE 2025-04-28 02:08:02,525 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:02,526 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:08:02,580 - 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-04-28 02:08:02,664 - DEBUG - VM ctest-pt_svm0-47341098 is NOT ready for SSH connections, VM status: ACTIVE 2025-04-28 02:08:07,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:07,664 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:08:07,829 - DEBUG - VM ctest-pt_svm0-47341098 is ready for SSH connections 2025-04-28 02:08:07,981 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:07,981 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:08:08,141 - DEBUG - VM ctest-pt_svm0-47341098 is ready for SSH connections 2025-04-28 02:08:08,297 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:08,297 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-47341098, IP 180.221.246.67, Port 22 2025-04-28 02:08:08,449 - DEBUG - VM ctest-pt_svm0-47341098 is ready for SSH connections 2025-04-28 02:08:08,455 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-si-22677670'] 2025-04-28 02:08:09,337 - INFO - Waiting for VM ctest-left_vm-26198562 to be up.. 2025-04-28 02:08:09,409 - DEBUG - VM is in ACTIVE state now 2025-04-28 02:08:09,409 - INFO - VM name : ctest-left_vm-26198562 2025-04-28 02:08:09,481 - DEBUG - VM ctest-left_vm-26198562 ID is 65aac2d0-877f-4eb5-8d5a-7f6c24b9eb20 2025-04-28 02:08:09,481 - DEBUG - VM ctest-left_vm-26198562 launched on Node cn-jenkins-deploy-platform-ansible-os-3232-1 2025-04-28 02:08:09,557 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/65aac2d0-877f-4eb5-8d5a-7f6c24b9eb20 2025-04-28 02:08:09,565 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/1733b4a9-ebdd-4618-8c7e-e3f3356656b8 2025-04-28 02:08:10,802 - 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.75 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.336 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.336/1.543/2.750/1.207 ms') 2025-04-28 02:08:10,802 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-26198562 passed 2025-04-28 02:08:10,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:10,954 - DEBUG - Waiting to SSH to VM ctest-left_vm-26198562, IP 208.16.30.3, Port 22 2025-04-28 02:08:11,104 - DEBUG - VM ctest-left_vm-26198562 is ready for SSH connections 2025-04-28 02:08:11,105 - INFO - Waiting for VM ctest-right_vm-21140181 to be up.. 2025-04-28 02:08:11,178 - DEBUG - VM is in ACTIVE state now 2025-04-28 02:08:11,178 - INFO - VM name : ctest-right_vm-21140181 2025-04-28 02:08:11,249 - DEBUG - VM ctest-right_vm-21140181 ID is d1156457-d88b-445d-bd3e-8d870576e890 2025-04-28 02:08:11,249 - DEBUG - VM ctest-right_vm-21140181 launched on Node cn-jenkins-deploy-platform-ansible-os-3232-1 2025-04-28 02:08:11,319 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/d1156457-d88b-445d-bd3e-8d870576e890 2025-04-28 02:08:11,326 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ceb9b577-3c9f-4ed1-bf94-f07dff92a9d0 2025-04-28 02:08:12,554 - 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.62 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.358 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.358/1.489/2.621/1.131 ms') 2025-04-28 02:08:12,555 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-21140181 passed 2025-04-28 02:08:12,701 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:12,701 - DEBUG - Waiting to SSH to VM ctest-right_vm-21140181, IP 201.13.71.67, Port 22 2025-04-28 02:08:12,860 - DEBUG - VM ctest-right_vm-21140181 is ready for SSH connections 2025-04-28 02:08:12,865 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219, 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-46385884:ctest-right_vn-59189569, 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-46385884:ctest-si-22677670'], 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-04-28 02:08:13,293 - INFO - Attaching policy ctest-policy-83745077 to vn ctest-left_vn-13580219 2025-04-28 02:08:13,825 - INFO - Attaching policy ctest-policy-83745077 to vn ctest-right_vn-59189569 2025-04-28 02:08:14,257 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-90519722'] created succesfully 2025-04-28 02:08:14,481 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-si-22677670'] created succesfully 2025-04-28 02:08:14,481 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:14,489 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:14,500 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:14,573 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/582c5226-9a7f-4ca4-a5fb-aea7d052d81d 2025-04-28 02:08:14,583 - DEBUG - SI 'ctest-si-22677670' has service template ref to ctest-service_template_1-90519722 2025-04-28 02:08:14,583 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:14,589 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:14,604 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:14,683 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/582c5226-9a7f-4ca4-a5fb-aea7d052d81d 2025-04-28 02:08:14,692 - DEBUG - Requesting: http://10.0.0.50:8082/port-tuple/e4cc5fb4-02c0-4209-a2d1-5ec66039604a 2025-04-28 02:08:14,700 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/0f39bbcf-10dd-4f21-a811-8b1e1eb7859b 2025-04-28 02:08:14,710 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ca89dc02-8b9c-4f65-840b-2a9709295c13 2025-04-28 02:08:14,722 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/d0545d4d-34b1-407b-a9ab-a1960e6de08e 2025-04-28 02:08:14,933 - DEBUG - VM is in ACTIVE state now 2025-04-28 02:08:15,305 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:08:15,313 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/0f39bbcf-10dd-4f21-a811-8b1e1eb7859b 2025-04-28 02:08:15,324 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ca89dc02-8b9c-4f65-840b-2a9709295c13 2025-04-28 02:08:15,333 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/d0545d4d-34b1-407b-a9ab-a1960e6de08e 2025-04-28 02:08:15,342 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/6d2df008-6830-4d3e-b799-1d100545a2e0 2025-04-28 02:08:15,349 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/891c7021-104d-4230-9cff-ef38c6b72589 2025-04-28 02:08:15,355 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/f810a314-aadd-4d09-93be-b3f51278aaea 2025-04-28 02:08:15,361 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/6b063c56-c924-45e6-bfc2-575da7619dce 2025-04-28 02:08:15,367 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/a3860848-bd98-4e55-b1fa-6c768caf58c9 2025-04-28 02:08:15,373 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/c845a2b3-0a70-4976-9bbd-86f42521cc0c 2025-04-28 02:08:15,382 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/09876adc-14c1-42fd-9756-b2944fb75549 2025-04-28 02:08:15,388 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/9c5b9e39-9637-442f-8c10-0fbce25e0de5 2025-04-28 02:08:15,395 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/e311645f-a3d6-41f1-aebb-9e29aac204a7 2025-04-28 02:08:15,716 - DEBUG - VM is in ACTIVE state now 2025-04-28 02:08:15,716 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:08:15,723 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/0f39bbcf-10dd-4f21-a811-8b1e1eb7859b 2025-04-28 02:08:15,732 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ca89dc02-8b9c-4f65-840b-2a9709295c13 2025-04-28 02:08:15,741 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/d0545d4d-34b1-407b-a9ab-a1960e6de08e 2025-04-28 02:08:16,995 - 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=0.827 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.443 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1016ms\r\nrtt min/avg/max/mdev = 0.443/0.635/0.827/0.192 ms') 2025-04-28 02:08:16,995 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-47341098 passed 2025-04-28 02:08:17,027 - DEBUG - VM ctest-pt_svm0-47341098 Tap interface: {'index': '6', 'name': 'tapca89dc02-8b', 'uuid': 'ca89dc02-8b9c-4f65-840b-2a9709295c13', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219:ctest-left_vn-13580219', '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-46385884:ctest-left_vn-13580219', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '208.16.30.4', 'mac_addr': '02:ca:89:dc:02:8b', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '35', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:ca89dc02-8b9c-4f65-840b-2a9709295c13', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', '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': 'd0545d4d-34b1-407b-a9ab-a1960e6de08e', '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-04-28 02:08:17,027 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219:ctest-left_vn-13580219 2025-04-28 02:08:17,032 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219:ctest-left_vn-13580219', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.209:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-04-28 02:08:17,193 - DEBUG - VM ctest-pt_svm0-47341098 : Tap interface tapca89dc02-8b is set to Active 2025-04-28 02:08:17,193 - DEBUG - Tap interface tapca89dc02-8b detail : {'index': '6', 'name': 'tapca89dc02-8b', 'uuid': 'ca89dc02-8b9c-4f65-840b-2a9709295c13', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219:ctest-left_vn-13580219', '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-46385884:ctest-left_vn-13580219', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '208.16.30.4', 'mac_addr': '02:ca:89:dc:02:8b', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '35', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:ca89dc02-8b9c-4f65-840b-2a9709295c13', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', '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': 'd0545d4d-34b1-407b-a9ab-a1960e6de08e', '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-04-28 02:08:17,385 - DEBUG - Starting Layer 2 verification in Agent 2025-04-28 02:08:17,396 - DEBUG - Layer 2 path is seen for VM MAC 02:ca:89:dc:02:8b in agent 10.0.0.50 2025-04-28 02:08:17,397 - DEBUG - Active layer 2 route in agent is present for VMI tapca89dc02-8b 2025-04-28 02:08:17,397 - DEBUG - L2 label(48) matches bw route table and interface table 2025-04-28 02:08:17,402 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 is found in Agent of node 10.0.0.50 2025-04-28 02:08:17,609 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 is consistent in agent 10.0.0.50 2025-04-28 02:08:17,609 - DEBUG - Route for VM IP ['208.16.30.4'] is consistent in agent 10.0.0.50 2025-04-28 02:08:17,609 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 verification for VM ctest-pt_svm0-47341098 in Agent 10.0.0.50 passed 2025-04-28 02:08:17,801 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50 2025-04-28 02:08:17,812 - DEBUG - Route for VM MAC 02:ca:89:dc:02:8b is consistent in agent 10.0.0.50 2025-04-28 02:08:17,829 - DEBUG - VM ctest-pt_svm0-47341098 Tap interface: {'index': '5', 'name': 'tap0f39bbcf-10', 'uuid': '0f39bbcf-10dd-4f21-a811-8b1e1eb7859b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577:ctest-mgmt_vn-91118577', '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-46385884:ctest-mgmt_vn-91118577', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '180.221.246.67', 'mac_addr': '02:0f:39:bb:cf:10', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '34', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:0f39bbcf-10dd-4f21-a811-8b1e1eb7859b', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', '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-04-28 02:08:17,830 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577:ctest-mgmt_vn-91118577 2025-04-28 02:08:17,834 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577:ctest-mgmt_vn-91118577', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577', 'table_label': '-1', 'vxlan_id': '8', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.209:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-04-28 02:08:18,039 - DEBUG - VM ctest-pt_svm0-47341098 : Tap interface tap0f39bbcf-10 is set to Active 2025-04-28 02:08:18,039 - DEBUG - Tap interface tap0f39bbcf-10 detail : {'index': '5', 'name': 'tap0f39bbcf-10', 'uuid': '0f39bbcf-10dd-4f21-a811-8b1e1eb7859b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577:ctest-mgmt_vn-91118577', '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-46385884:ctest-mgmt_vn-91118577', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '180.221.246.67', 'mac_addr': '02:0f:39:bb:cf:10', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '34', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:0f39bbcf-10dd-4f21-a811-8b1e1eb7859b', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', '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-04-28 02:08:18,229 - DEBUG - Starting Layer 2 verification in Agent 2025-04-28 02:08:18,241 - DEBUG - Layer 2 path is seen for VM MAC 02:0f:39:bb:cf:10 in agent 10.0.0.50 2025-04-28 02:08:18,241 - DEBUG - Active layer 2 route in agent is present for VMI tap0f39bbcf-10 2025-04-28 02:08:18,241 - DEBUG - L2 label(43) matches bw route table and interface table 2025-04-28 02:08:18,246 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is found in Agent of node 10.0.0.50 2025-04-28 02:08:18,450 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 is consistent in agent 10.0.0.50 2025-04-28 02:08:18,450 - DEBUG - Route for VM IP ['180.221.246.67'] is consistent in agent 10.0.0.50 2025-04-28 02:08:18,451 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-mgmt_vn-91118577 verification for VM ctest-pt_svm0-47341098 in Agent 10.0.0.50 passed 2025-04-28 02:08:18,645 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50 2025-04-28 02:08:18,655 - DEBUG - Route for VM MAC 02:0f:39:bb:cf:10 is consistent in agent 10.0.0.50 2025-04-28 02:08:18,676 - DEBUG - VM ctest-pt_svm0-47341098 Tap interface: {'index': '7', 'name': 'tapd0545d4d-34', 'uuid': 'd0545d4d-34b1-407b-a9ab-a1960e6de08e', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569:ctest-right_vn-59189569', '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-46385884:ctest-right_vn-59189569', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '201.13.71.68', 'mac_addr': '02:d0:54:5d:4d:34', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '36', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:d0545d4d-34b1-407b-a9ab-a1960e6de08e', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', 'admin_state': 'Enabled', 'flow_key_idx': '28', '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': 'ca89dc02-8b9c-4f65-840b-2a9709295c13', '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-04-28 02:08:18,676 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569:ctest-right_vn-59189569 2025-04-28 02:08:18,683 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569:ctest-right_vn-59189569', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.209:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-04-28 02:08:18,893 - DEBUG - VM ctest-pt_svm0-47341098 : Tap interface tapd0545d4d-34 is set to Active 2025-04-28 02:08:18,893 - DEBUG - Tap interface tapd0545d4d-34 detail : {'index': '7', 'name': 'tapd0545d4d-34', 'uuid': 'd0545d4d-34b1-407b-a9ab-a1960e6de08e', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569:ctest-right_vn-59189569', '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-46385884:ctest-right_vn-59189569', 'vm_uuid': 'e1c375a7-142c-47a8-a9ec-207cf40cbeb6', 'vm_name': 'ctest-pt_svm0-47341098', 'ip_addr': '201.13.71.68', 'mac_addr': '02:d0:54:5d:4d:34', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '36', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-46385884:d0545d4d-34b1-407b-a9ab-a1960e6de08e', 'sg_uuid_list': ['41404d39-dcf4-453e-a304-7ea969720d8f'], 'static_route_list': None, 'vm_project_uuid': '2d2aa3e2-c426-4c59-85e6-ee56931afd99', 'admin_state': 'Enabled', 'flow_key_idx': '28', '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': 'ca89dc02-8b9c-4f65-840b-2a9709295c13', '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-04-28 02:08:19,085 - DEBUG - Starting Layer 2 verification in Agent 2025-04-28 02:08:19,096 - DEBUG - Layer 2 path is seen for VM MAC 02:d0:54:5d:4d:34 in agent 10.0.0.50 2025-04-28 02:08:19,096 - DEBUG - Active layer 2 route in agent is present for VMI tapd0545d4d-34 2025-04-28 02:08:19,096 - DEBUG - L2 label(53) matches bw route table and interface table 2025-04-28 02:08:19,102 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 is found in Agent of node 10.0.0.50 2025-04-28 02:08:19,309 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 is consistent in agent 10.0.0.50 2025-04-28 02:08:19,309 - DEBUG - Route for VM IP ['201.13.71.68'] is consistent in agent 10.0.0.50 2025-04-28 02:08:19,310 - DEBUG - VN default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 verification for VM ctest-pt_svm0-47341098 in Agent 10.0.0.50 passed 2025-04-28 02:08:19,505 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50 2025-04-28 02:08:19,516 - DEBUG - Route for VM MAC 02:d0:54:5d:4d:34 is consistent in agent 10.0.0.50 2025-04-28 02:08:19,516 - INFO - VM ctest-pt_svm0-47341098 verifications in Compute nodes passed 2025-04-28 02:08:19,517 - DEBUG - The SVMs in the SI are : [] 2025-04-28 02:08:19,517 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:08:19,525 - DEBUG - Service VM for SI 'ctest-si-22677670' is launched 2025-04-28 02:08:19,525 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:19,535 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:19,548 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:19,623 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/582c5226-9a7f-4ca4-a5fb-aea7d052d81d 2025-04-28 02:08:19,632 - DEBUG - SI ctest-si-22677670 has Port Tuple: ['ctest-port_tuple-93780786'] 2025-04-28 02:08:19,733 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-46385884:ctest-left_vn-13580219 2025-04-28 02:08:19,881 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-46385884:ctest-right_vn-59189569 2025-04-28 02:08:20,039 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,048 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,048 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,054 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,054 - INFO - Route to 201.13.71.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-3232-1, and SI ctest-si-22677670 is seen as the NH 2025-04-28 02:08:20,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,234 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,234 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,240 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,240 - INFO - Route to 208.16.30.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-3232-1, and SI ctest-si-22677670 is seen as the NH 2025-04-28 02:08:20,240 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,240 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-04-28 02:08:20,240 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 201.13.71.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-04-28 02:08:20,240 - DEBUG - ping -s 56 -c 3 -W 1 201.13.71.67 2025-04-28 02:08:24,150 - DEBUG - PING 201.13.71.67 (201.13.71.67): 56 data bytes 64 bytes from 201.13.71.67: seq=0 ttl=61 time=4.780 ms 64 bytes from 201.13.71.67: seq=1 ttl=61 time=1.330 ms 64 bytes from 201.13.71.67: seq=2 ttl=61 time=1.488 ms --- 201.13.71.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.330/2.532/4.780 ms 2025-04-28 02:08:24,150 - INFO - Ping to IP 201.13.71.67 from VM ctest-left_vm-26198562 passed 2025-04-28 02:08:24,150 - INFO - Dettaching policy ctest-policy-83745077 from vn ctest-right_vn-59189569 2025-04-28 02:08:24,349 - INFO - Dettaching policy ctest-policy-83745077 from vn ctest-left_vn-13580219 2025-04-28 02:08:24,615 - INFO - Deleted policy ctest-policy-83745077 2025-04-28 02:08:24,615 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-si-22677670'] 2025-04-28 02:08:24,722 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-46385884', 'ctest-si-22677670'] 2025-04-28 02:08:24,722 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:24,730 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:24,745 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:24,817 - DEBUG - Service instance ctest-si-22677670 removed from api server 2025-04-28 02:08:24,817 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e1c375a7-142c-47a8-a9ec-207cf40cbeb6 2025-04-28 02:08:24,824 - DEBUG - All Service VMs unlinked from SI ctest-si-22677670 2025-04-28 02:08:24,829 - DEBUG - 0 svc intances found in all projects. They are [] 2025-04-28 02:08:24,829 - DEBUG - 0 svc intances found in current project. They are [] 2025-04-28 02:08:24,877 - DEBUG - 0 svc intances found in all projects. They are [] 2025-04-28 02:08:24,877 - DEBUG - 0 svc intances found in current project. They are [] 2025-04-28 02:08:24,877 - INFO - Deleting VM ctest-pt_svm0-47341098 2025-04-28 02:08:24,948 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-90519722'] 2025-04-28 02:08:25,049 - INFO - Service template: ['default-domain', 'ctest-service_template_1-90519722'] deleted successfully. 2025-04-28 02:08:25,049 - INFO - Deleting VM ctest-right_vm-21140181 2025-04-28 02:08:25,123 - INFO - Deleting VM ctest-left_vm-26198562 2025-04-28 02:08:25,200 - INFO - Deleting VN ctest-right_vn-59189569 2025-04-28 02:08:25,234 - DEBUG - VN 2a8970d1-faa6-4dfa-a756-da0bc64d0fb4 still in use: Unable to complete operation on network 2a8970d1-faa6-4dfa-a756-da0bc64d0fb4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8b3771f3-d683-4010-86d8-0f6c9660278a'] 2025-04-28 02:08:25,234 - WARNING - Deleting VN ctest-right_vn-59189569 failed..Will retry 2025-04-28 02:08:27,318 - DEBUG - Response for deleting network () 2025-04-28 02:08:27,318 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/7216a089-127a-4b5a-aa86-a24ab289b823 2025-04-28 02:08:27,326 - DEBUG - Response Code: 404 2025-04-28 02:08:27,326 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:27,331 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:27,383 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:27,455 - INFO - Validated that VN ctest-right_vn-59189569 is not found in API Server 2025-04-28 02:08:27,464 - DEBUG - VN ctest-right_vn-59189569 is not present in Agent 10.0.0.50 2025-04-28 02:08:27,464 - INFO - Validated that VN ctest-right_vn-59189569 is not in any agent 2025-04-28 02:08:27,479 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-59189569 info 2025-04-28 02:08:27,479 - INFO - Deleting VN ctest-left_vn-13580219 2025-04-28 02:08:27,567 - DEBUG - Response for deleting network () 2025-04-28 02:08:27,568 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/2a98dc58-84ff-4024-97ed-76938fc95fea 2025-04-28 02:08:27,573 - DEBUG - Response Code: 404 2025-04-28 02:08:27,573 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:27,577 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:27,632 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:27,706 - INFO - Validated that VN ctest-left_vn-13580219 is not found in API Server 2025-04-28 02:08:27,716 - DEBUG - VN ctest-left_vn-13580219 is not present in Agent 10.0.0.50 2025-04-28 02:08:27,716 - INFO - Validated that VN ctest-left_vn-13580219 is not in any agent 2025-04-28 02:08:27,730 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-13580219 info 2025-04-28 02:08:27,730 - INFO - Deleting VN ctest-mgmt_vn-91118577 2025-04-28 02:08:27,823 - DEBUG - Response for deleting network () 2025-04-28 02:08:27,823 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/6a067ab4-aebc-43ec-b5ac-0ecf8a9bac71 2025-04-28 02:08:27,829 - DEBUG - Response Code: 404 2025-04-28 02:08:27,830 - DEBUG - Requesting: http://10.0.0.50:8082/domains 2025-04-28 02:08:27,834 - DEBUG - Requesting: http://10.0.0.50:8082/domain/09530269-b2df-4ef6-995f-dfbc59aefc94 2025-04-28 02:08:27,890 - DEBUG - Requesting: http://10.0.0.50:8082/project/2d2aa3e2-c426-4c59-85e6-ee56931afd99 2025-04-28 02:08:27,968 - INFO - Validated that VN ctest-mgmt_vn-91118577 is not found in API Server 2025-04-28 02:08:27,981 - DEBUG - VN ctest-mgmt_vn-91118577 is not present in Agent 10.0.0.50 2025-04-28 02:08:27,981 - INFO - Validated that VN ctest-mgmt_vn-91118577 is not in any agent 2025-04-28 02:08:27,995 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-91118577 info 2025-04-28 02:08:28,259 - DEBUG - No XMPP flaps were noticed during the test 2025-04-28 02:08:28,259 - INFO -
2025-04-28 02:08:28,259 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:09]
2025-04-28 02:08:28,259 - INFO - -------------------------------------------------------------------------------- 2025-04-28 02:08:28,900 - INFO - Deleted project: ctest-TestSvcRegr-46385884, ID : 2d2aa3e2-c426-4c59-85e6-ee56931afd99