2025-12-18 02:13:03,974 - INFO - Domain Default found not creating 2025-12-18 02:13:04,516 - INFO - Project ctest-TestSvcRegr-60917613 not found, creating it 2025-12-18 02:13:05,396 - INFO - Created Project:ctest-TestSvcRegr-60917613, ID : 166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:07,154 - INFO - ================================================================================ 2025-12-18 02:13:07,154 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-12-18 02:13:07,432 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.18': '0'}} with 2025-12-18 02:13:07,432 - INFO - Initial checks done. Running the testcase now 2025-12-18 02:13:07,432 - INFO - 2025-12-18 02:13:08,105 - DEBUG - Response for create_network : {'network': {'id': 'a8325e4b-b2ad-4c92-ac06-4739ac0f12b1', 'name': 'ctest-mgmt_vn-46890528', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'project_id': '166d041b38074d2aa782e8a3a88d857f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-mgmt_vn-46890528'], 'port_security_enabled': True, 'description': ''}} 2025-12-18 02:13:08,324 - DEBUG - Response for create_subnet : {'subnet': {'id': '7d9f766c-1433-40ed-8a97-6e4746540f17', 'name': '', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'network_id': 'a8325e4b-b2ad-4c92-ac06-4739ac0f12b1', 'ip_version': 4, 'cidr': '138.195.74.64/26', 'allocation_pools': [{'start': '138.195.74.66', 'end': '138.195.74.126'}], 'gateway_ip': '138.195.74.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.195.74.66', 'tags': [], 'project_id': '166d041b38074d2aa782e8a3a88d857f'}} 2025-12-18 02:13:08,349 - INFO - Created VN ctest-mgmt_vn-46890528 2025-12-18 02:13:08,405 - DEBUG - VN ctest-mgmt_vn-46890528 UUID is a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:08,535 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:08,836 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:08,884 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:08,901 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:08,980 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:08,993 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:09,004 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:13:09,015 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:13:09,025 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/531521a0-a6f1-41da-b77b-80ddaf35aa05 2025-12-18 02:13:09,036 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-18 02:13:09,036 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:09,046 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:13:09,063 - INFO - Verified VN network id 12 for VN a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:09,063 - INFO - Verifications in API Server for VN ctest-mgmt_vn-46890528 passed 2025-12-18 02:13:09,063 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:09,077 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:13:09,085 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:13:09,093 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/531521a0-a6f1-41da-b77b-80ddaf35aa05 2025-12-18 02:13:09,109 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-60917613', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12119853226420685970', 'uuid-lslong': '12395673337409245873'}, 'enable': 'true', 'created': '2025-12-18T02:13:08', 'last-modified': '2025-12-18T02:13:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.827056'} 2025-12-18 02:13:09,119 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-18 02:13:09,119 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-46890528 passed 2025-12-18 02:13:09,120 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-46890528 in API_Server ====== 2025-12-18 02:13:09,120 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:09,129 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:09,142 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:09,226 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a8325e4b-b2ad-4c92-ac06-4739ac0f12b1 2025-12-18 02:13:09,238 - DEBUG - =>VN ctest-mgmt_vn-46890528 has no policy to be verified 2025-12-18 02:13:09,238 - DEBUG - Verifying the vn in opserver 2025-12-18 02:13:09,238 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 virtual network link through opserver 10.0.0.141 2025-12-18 02:13:09,238 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2025-12-18 02:13:09,551 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2025-12-18 02:13:09,646 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528?flat'} 2025-12-18 02:13:09,646 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is found in opserver 2025-12-18 02:13:09,657 - DEBUG - Do not have enough data to verify VN in agent 2025-12-18 02:13:09,664 - DEBUG - VRF ids for VN ctest-mgmt_vn-46890528: {} 2025-12-18 02:13:09,903 - DEBUG - Response for create_network : {'network': {'id': 'd4075b62-ae74-4a4b-be4a-bd17be659c25', 'name': 'ctest-left_vn-20546113', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'project_id': '166d041b38074d2aa782e8a3a88d857f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-left_vn-20546113'], 'port_security_enabled': True, 'description': ''}} 2025-12-18 02:13:10,150 - DEBUG - Response for create_subnet : {'subnet': {'id': '7712f243-38eb-4fee-8ed8-5c1aa6035413', 'name': '', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'network_id': 'd4075b62-ae74-4a4b-be4a-bd17be659c25', 'ip_version': 4, 'cidr': '201.23.247.64/26', 'allocation_pools': [{'start': '201.23.247.66', 'end': '201.23.247.126'}], 'gateway_ip': '201.23.247.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.23.247.66', 'tags': [], 'project_id': '166d041b38074d2aa782e8a3a88d857f'}} 2025-12-18 02:13:10,178 - INFO - Created VN ctest-left_vn-20546113 2025-12-18 02:13:10,233 - DEBUG - VN ctest-left_vn-20546113 UUID is d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,371 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:10,381 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:10,397 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:10,481 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,497 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,509 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:13:10,518 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:13:10,528 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/4fbef9a4-d8d2-496c-8656-e8e9016978e9 2025-12-18 02:13:10,538 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-18 02:13:10,538 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,550 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:13:10,571 - INFO - Verified VN network id 13 for VN d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,571 - INFO - Verifications in API Server for VN ctest-left_vn-20546113 passed 2025-12-18 02:13:10,571 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,584 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:13:10,594 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:13:10,603 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/4fbef9a4-d8d2-496c-8656-e8e9016978e9 2025-12-18 02:13:10,618 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-60917613', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15278280740269476427', 'uuid-lslong': '13711979925159124005'}, 'enable': 'true', 'created': '2025-12-18T02:13:09', 'last-modified': '2025-12-18T02:13:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.513968'} 2025-12-18 02:13:10,626 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-18 02:13:10,626 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-20546113 passed 2025-12-18 02:13:10,626 - DEBUG - ====Verifying policy data for ctest-left_vn-20546113 in API_Server ====== 2025-12-18 02:13:10,626 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:10,633 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:10,646 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:10,725 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d4075b62-ae74-4a4b-be4a-bd17be659c25 2025-12-18 02:13:10,735 - DEBUG - =>VN ctest-left_vn-20546113 has no policy to be verified 2025-12-18 02:13:10,735 - DEBUG - Verifying the vn in opserver 2025-12-18 02:13:10,736 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 virtual network link through opserver 10.0.0.141 2025-12-18 02:13:10,736 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2025-12-18 02:13:10,747 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113?flat'} 2025-12-18 02:13:10,747 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 is found in opserver 2025-12-18 02:13:10,759 - DEBUG - Do not have enough data to verify VN in agent 2025-12-18 02:13:10,765 - DEBUG - VRF ids for VN ctest-left_vn-20546113: {} 2025-12-18 02:13:10,993 - DEBUG - Response for create_network : {'network': {'id': 'fb1edc55-29b9-47b8-a184-7266827adccc', 'name': 'ctest-right_vn-22716447', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'project_id': '166d041b38074d2aa782e8a3a88d857f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-right_vn-22716447'], 'port_security_enabled': True, 'description': ''}} 2025-12-18 02:13:11,225 - DEBUG - Response for create_subnet : {'subnet': {'id': '76b4441d-5a16-418b-99cb-857ef9f4f2df', 'name': '', 'tenant_id': '166d041b38074d2aa782e8a3a88d857f', 'network_id': 'fb1edc55-29b9-47b8-a184-7266827adccc', 'ip_version': 4, 'cidr': '128.128.71.128/26', 'allocation_pools': [{'start': '128.128.71.130', 'end': '128.128.71.190'}], 'gateway_ip': '128.128.71.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.128.71.130', 'tags': [], 'project_id': '166d041b38074d2aa782e8a3a88d857f'}} 2025-12-18 02:13:11,248 - INFO - Created VN ctest-right_vn-22716447 2025-12-18 02:13:11,305 - DEBUG - VN ctest-right_vn-22716447 UUID is fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,437 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:11,444 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:11,457 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:11,543 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,561 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,575 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:13:11,586 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:13:11,595 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0c0d51d3-de2e-4f08-9b6b-3175c5447d8a 2025-12-18 02:13:11,604 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-18 02:13:11,604 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,617 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:13:11,636 - INFO - Verified VN network id 14 for VN fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,637 - INFO - Verifications in API Server for VN ctest-right_vn-22716447 passed 2025-12-18 02:13:11,638 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,651 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:13:11,661 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:13:11,671 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0c0d51d3-de2e-4f08-9b6b-3175c5447d8a 2025-12-18 02:13:11,688 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-60917613', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18095142611151570872', 'uuid-lslong': '11638553121633524940'}, 'enable': 'true', 'created': '2025-12-18T02:13:10', 'last-modified': '2025-12-18T02:13:11', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500852'} 2025-12-18 02:13:11,695 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-18 02:13:11,695 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-22716447 passed 2025-12-18 02:13:11,695 - DEBUG - ====Verifying policy data for ctest-right_vn-22716447 in API_Server ====== 2025-12-18 02:13:11,696 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:13:11,703 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:13:11,716 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:13:11,801 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fb1edc55-29b9-47b8-a184-7266827adccc 2025-12-18 02:13:11,813 - DEBUG - =>VN ctest-right_vn-22716447 has no policy to be verified 2025-12-18 02:13:11,813 - DEBUG - Verifying the vn in opserver 2025-12-18 02:13:11,813 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 virtual network link through opserver 10.0.0.141 2025-12-18 02:13:11,813 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2025-12-18 02:13:11,827 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447?flat'} 2025-12-18 02:13:11,827 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 is found in opserver 2025-12-18 02:13:11,841 - DEBUG - Do not have enough data to verify VN in agent 2025-12-18 02:13:11,847 - DEBUG - VRF ids for VN ctest-right_vn-22716447: {} 2025-12-18 02:13:11,941 - DEBUG - Services list from nova: [, , ] 2025-12-18 02:13:13,135 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4899-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4899-1) 2025-12-18 02:13:14,441 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4899-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4899-1) 2025-12-18 02:13:14,441 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-96798176'] 2025-12-18 02:13:14,833 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96798176'] created succesfully 2025-12-18 02:13:15,221 - DEBUG - Image by name cirros_in_net not found 2025-12-18 02:13:15,221 - DEBUG - Installing image cirros_in_net 2025-12-18 02:13:15,221 - 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-12-18 02:13:15,221 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-12-18 02:13:15,222 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-12-18 02:13:15,248 - 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-12-18 02:13:15,373 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-12-18 02:13:15,374 - DEBUG - Unzip image 2025-12-18 02:13:15,374 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-12-18 02:13:15,586 - DEBUG - Try to create image in user context 2025-12-18 02:13:15,856 - DEBUG - Image has been created in user context 2025-12-18 02:13:15,856 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-12-18 02:13:15,877 - DEBUG - Downloaded image has been removed 2025-12-18 02:13:15,894 - DEBUG - Uploaded image status = active 2025-12-18 02:13:17,185 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4899-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4899-1) 2025-12-18 02:13:17,185 - INFO - Waiting for VM ctest-pt_svm0-59131070 to be up.. 2025-12-18 02:13:17,234 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-18 02:13:22,351 - DEBUG - VM is in ACTIVE state now 2025-12-18 02:13:22,351 - INFO - VM name : ctest-pt_svm0-59131070 2025-12-18 02:13:22,468 - DEBUG - VM ctest-pt_svm0-59131070 ID is 6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:13:22,496 - DEBUG - VM ctest-pt_svm0-59131070 launched on Node cn-jenkins-deploy-platform-ansible-os-4899-1 2025-12-18 02:13:22,834 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:13:22,846 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4f111e41-a737-486a-bd23-0084f6471cb9 2025-12-18 02:13:22,859 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/dfdec397-136d-4990-882e-f2ebb06d1181 2025-12-18 02:13:22,873 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e1456533-66a9-45ff-b94b-5fc184e8599b 2025-12-18 02:13:26,198 - 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-12-18 02:13:26,198 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-59131070 failed! 2025-12-18 02:13:26,257 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is 138.195.74.65 and allocation pool is NOT set 2025-12-18 02:13:30,322 - 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 1002ms') 2025-12-18 02:13:30,322 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-59131070 failed! 2025-12-18 02:13:30,377 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is 138.195.74.65 and allocation pool is NOT set 2025-12-18 02:13:34,450 - 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-12-18 02:13:34,450 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-59131070 failed! 2025-12-18 02:13:34,509 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is 138.195.74.65 and allocation pool is NOT set 2025-12-18 02:13:38,588 - 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 1013ms') 2025-12-18 02:13:38,588 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-59131070 failed! 2025-12-18 02:13:38,649 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is 138.195.74.65 and allocation pool is NOT set 2025-12-18 02:13:40,724 - 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=10.4 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=1.05 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 = 1.051/5.712/10.374/4.661 ms') 2025-12-18 02:13:40,724 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-59131070 passed 2025-12-18 02:13:40,881 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:13:40,881 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:13:40,951 - 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-12-18 02:13:41,091 - DEBUG - VM ctest-pt_svm0-59131070 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-18 02:13:46,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:13:46,093 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:13:46,159 - 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-12-18 02:13:46,276 - DEBUG - VM ctest-pt_svm0-59131070 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-18 02:13:51,277 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:13:51,277 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:13:51,343 - 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-12-18 02:13:51,446 - DEBUG - VM ctest-pt_svm0-59131070 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-18 02:13:56,447 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:13:56,447 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:13:56,515 - 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-12-18 02:13:56,606 - DEBUG - VM ctest-pt_svm0-59131070 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-18 02:14:01,607 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:01,608 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:14:01,779 - DEBUG - VM ctest-pt_svm0-59131070 is ready for SSH connections 2025-12-18 02:14:01,933 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:01,933 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:14:02,099 - DEBUG - VM ctest-pt_svm0-59131070 is ready for SSH connections 2025-12-18 02:14:02,257 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:02,257 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-59131070, IP 138.195.74.67, Port 22 2025-12-18 02:14:02,423 - DEBUG - VM ctest-pt_svm0-59131070 is ready for SSH connections 2025-12-18 02:14:02,429 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-si-63036939'] 2025-12-18 02:14:03,353 - INFO - Waiting for VM ctest-left_vm-77215068 to be up.. 2025-12-18 02:14:03,454 - DEBUG - VM is in ACTIVE state now 2025-12-18 02:14:03,454 - INFO - VM name : ctest-left_vm-77215068 2025-12-18 02:14:03,554 - DEBUG - VM ctest-left_vm-77215068 ID is 8118df10-0a23-4cd5-bef7-fcc01115ac5f 2025-12-18 02:14:03,554 - DEBUG - VM ctest-left_vm-77215068 launched on Node cn-jenkins-deploy-platform-ansible-os-4899-1 2025-12-18 02:14:03,648 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/8118df10-0a23-4cd5-bef7-fcc01115ac5f 2025-12-18 02:14:03,657 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e89a7f5b-0efd-4b4a-be2b-97f27765a15b 2025-12-18 02:14:04,913 - 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.77 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.823 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.823/2.298/3.773/1.475 ms') 2025-12-18 02:14:04,913 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-77215068 passed 2025-12-18 02:14:05,069 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:05,069 - DEBUG - Waiting to SSH to VM ctest-left_vm-77215068, IP 201.23.247.67, Port 22 2025-12-18 02:14:05,247 - DEBUG - VM ctest-left_vm-77215068 is ready for SSH connections 2025-12-18 02:14:05,247 - INFO - Waiting for VM ctest-right_vm-67478651 to be up.. 2025-12-18 02:14:05,338 - DEBUG - VM is in ACTIVE state now 2025-12-18 02:14:05,338 - INFO - VM name : ctest-right_vm-67478651 2025-12-18 02:14:05,434 - DEBUG - VM ctest-right_vm-67478651 ID is 4bf4778e-e58a-4e45-be75-a206915e3144 2025-12-18 02:14:05,434 - DEBUG - VM ctest-right_vm-67478651 launched on Node cn-jenkins-deploy-platform-ansible-os-4899-1 2025-12-18 02:14:05,551 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4bf4778e-e58a-4e45-be75-a206915e3144 2025-12-18 02:14:05,564 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/9aca32d3-a7bf-410c-830a-9af4e972fd11 2025-12-18 02:14:06,832 - 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=3.87 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.609 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.609/2.240/3.871/1.631 ms') 2025-12-18 02:14:06,832 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-67478651 passed 2025-12-18 02:14:06,993 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:06,993 - DEBUG - Waiting to SSH to VM ctest-right_vm-67478651, IP 128.128.71.131, Port 22 2025-12-18 02:14:07,163 - DEBUG - VM ctest-right_vm-67478651 is ready for SSH connections 2025-12-18 02:14:07,170 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113, 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-60917613:ctest-right_vn-22716447, 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-60917613:ctest-si-63036939'], 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-12-18 02:14:07,617 - INFO - Attaching policy ctest-policy-46919908 to vn ctest-left_vn-20546113 2025-12-18 02:14:08,185 - INFO - Attaching policy ctest-policy-46919908 to vn ctest-right_vn-22716447 2025-12-18 02:14:08,705 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-96798176'] created succesfully 2025-12-18 02:14:08,957 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-si-63036939'] created succesfully 2025-12-18 02:14:08,957 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:08,968 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:08,984 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:09,062 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8249e304-d5a3-4484-b5ca-07d73c3de1f9 2025-12-18 02:14:09,077 - DEBUG - SI 'ctest-si-63036939' has service template ref to ctest-service_template_1-96798176 2025-12-18 02:14:09,077 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:09,086 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:09,101 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:09,197 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8249e304-d5a3-4484-b5ca-07d73c3de1f9 2025-12-18 02:14:09,211 - DEBUG - Requesting: http://10.0.0.141:8082/port-tuple/fc1c8c2f-1c09-44a9-a91a-e2ed82e0cbc2 2025-12-18 02:14:09,228 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4f111e41-a737-486a-bd23-0084f6471cb9 2025-12-18 02:14:09,244 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/dfdec397-136d-4990-882e-f2ebb06d1181 2025-12-18 02:14:09,259 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e1456533-66a9-45ff-b94b-5fc184e8599b 2025-12-18 02:14:09,606 - DEBUG - VM is in ACTIVE state now 2025-12-18 02:14:10,100 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:14:10,111 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4f111e41-a737-486a-bd23-0084f6471cb9 2025-12-18 02:14:10,122 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/dfdec397-136d-4990-882e-f2ebb06d1181 2025-12-18 02:14:10,134 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e1456533-66a9-45ff-b94b-5fc184e8599b 2025-12-18 02:14:10,146 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/4c954f53-c29b-4dc9-b175-4f482ae66718 2025-12-18 02:14:10,154 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/ae3284fc-7e62-45f6-a116-e2be4b14dd0a 2025-12-18 02:14:10,165 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/f288160a-4ae6-4500-ab85-4d689e4a1d45 2025-12-18 02:14:10,176 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/43c830b6-1061-42f9-a167-7948ac3b6418 2025-12-18 02:14:10,186 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/9a5984b2-5524-44ee-9588-b9190c063994 2025-12-18 02:14:10,196 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/c24a2a91-3895-46eb-8da9-e983ab707088 2025-12-18 02:14:10,209 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/53421008-64c1-4010-b5ee-2491513e0db0 2025-12-18 02:14:10,219 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/652de9ca-9cee-4d5b-aaeb-1a2f1e39253a 2025-12-18 02:14:10,230 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/e461656e-4592-40cf-bc45-bd698512cf73 2025-12-18 02:14:10,762 - DEBUG - VM is in ACTIVE state now 2025-12-18 02:14:10,762 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:14:10,773 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4f111e41-a737-486a-bd23-0084f6471cb9 2025-12-18 02:14:10,787 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/dfdec397-136d-4990-882e-f2ebb06d1181 2025-12-18 02:14:10,799 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e1456533-66a9-45ff-b94b-5fc184e8599b 2025-12-18 02:14:12,054 - 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.39 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.904 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.904/1.146/1.389/0.242 ms') 2025-12-18 02:14:12,054 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-59131070 passed 2025-12-18 02:14:12,099 - DEBUG - VM ctest-pt_svm0-59131070 Tap interface: {'index': '6', 'name': 'tapdfdec397-13', 'uuid': 'dfdec397-136d-4990-882e-f2ebb06d1181', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113:ctest-left_vn-20546113', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '201.23.247.68', 'mac_addr': '02:df:de:c3:97:13', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:dfdec397-136d-4990-882e-f2ebb06d1181', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', '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': 'e1456533-66a9-45ff-b94b-5fc184e8599b', '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-12-18 02:14:12,100 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113:ctest-left_vn-20546113 2025-12-18 02:14:12,105 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113:ctest-left_vn-20546113', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-18 02:14:12,273 - DEBUG - VM ctest-pt_svm0-59131070 : Tap interface tapdfdec397-13 is set to Active 2025-12-18 02:14:12,273 - DEBUG - Tap interface tapdfdec397-13 detail : {'index': '6', 'name': 'tapdfdec397-13', 'uuid': 'dfdec397-136d-4990-882e-f2ebb06d1181', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113:ctest-left_vn-20546113', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '201.23.247.68', 'mac_addr': '02:df:de:c3:97:13', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:dfdec397-136d-4990-882e-f2ebb06d1181', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', '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': 'e1456533-66a9-45ff-b94b-5fc184e8599b', '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-12-18 02:14:12,469 - DEBUG - Starting Layer 2 verification in Agent 2025-12-18 02:14:12,481 - DEBUG - Layer 2 path is seen for VM MAC 02:df:de:c3:97:13 in agent 10.0.0.141 2025-12-18 02:14:12,481 - DEBUG - Active layer 2 route in agent is present for VMI tapdfdec397-13 2025-12-18 02:14:12,481 - DEBUG - L2 label(48) matches bw route table and interface table 2025-12-18 02:14:12,488 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 is found in Agent of node 10.0.0.141 2025-12-18 02:14:12,717 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 is consistent in agent 10.0.0.141 2025-12-18 02:14:12,717 - DEBUG - Route for VM IP ['201.23.247.68'] is consistent in agent 10.0.0.141 2025-12-18 02:14:12,717 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 verification for VM ctest-pt_svm0-59131070 in Agent 10.0.0.141 passed 2025-12-18 02:14:12,925 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2025-12-18 02:14:12,941 - DEBUG - Route for VM MAC 02:df:de:c3:97:13 is consistent in agent 10.0.0.141 2025-12-18 02:14:12,965 - DEBUG - VM ctest-pt_svm0-59131070 Tap interface: {'index': '5', 'name': 'tap4f111e41-a7', 'uuid': '4f111e41-a737-486a-bd23-0084f6471cb9', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528:ctest-mgmt_vn-46890528', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '138.195.74.67', 'mac_addr': '02:4f:11:1e:41:a7', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '39', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:4f111e41-a737-486a-bd23-0084f6471cb9', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', '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-12-18 02:14:12,965 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528:ctest-mgmt_vn-46890528 2025-12-18 02:14:12,973 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528:ctest-mgmt_vn-46890528', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.18:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-18 02:14:13,145 - DEBUG - VM ctest-pt_svm0-59131070 : Tap interface tap4f111e41-a7 is set to Active 2025-12-18 02:14:13,145 - DEBUG - Tap interface tap4f111e41-a7 detail : {'index': '5', 'name': 'tap4f111e41-a7', 'uuid': '4f111e41-a737-486a-bd23-0084f6471cb9', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528:ctest-mgmt_vn-46890528', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '138.195.74.67', 'mac_addr': '02:4f:11:1e:41:a7', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '39', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:4f111e41-a737-486a-bd23-0084f6471cb9', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', '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-12-18 02:14:13,349 - DEBUG - Starting Layer 2 verification in Agent 2025-12-18 02:14:13,360 - DEBUG - Layer 2 path is seen for VM MAC 02:4f:11:1e:41:a7 in agent 10.0.0.141 2025-12-18 02:14:13,360 - DEBUG - Active layer 2 route in agent is present for VMI tap4f111e41-a7 2025-12-18 02:14:13,360 - DEBUG - L2 label(43) matches bw route table and interface table 2025-12-18 02:14:13,366 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is found in Agent of node 10.0.0.141 2025-12-18 02:14:13,582 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 is consistent in agent 10.0.0.141 2025-12-18 02:14:13,583 - DEBUG - Route for VM IP ['138.195.74.67'] is consistent in agent 10.0.0.141 2025-12-18 02:14:13,583 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-mgmt_vn-46890528 verification for VM ctest-pt_svm0-59131070 in Agent 10.0.0.141 passed 2025-12-18 02:14:13,781 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2025-12-18 02:14:13,791 - DEBUG - Route for VM MAC 02:4f:11:1e:41:a7 is consistent in agent 10.0.0.141 2025-12-18 02:14:13,815 - DEBUG - VM ctest-pt_svm0-59131070 Tap interface: {'index': '7', 'name': 'tape1456533-66', 'uuid': 'e1456533-66a9-45ff-b94b-5fc184e8599b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447:ctest-right_vn-22716447', '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': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '128.128.71.132', 'mac_addr': '02:e1:45:65:33:66', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:e1456533-66a9-45ff-b94b-5fc184e8599b', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': 'dfdec397-136d-4990-882e-f2ebb06d1181', '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-12-18 02:14:13,816 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447:ctest-right_vn-22716447 2025-12-18 02:14:13,823 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447:ctest-right_vn-22716447', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.18:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-18 02:14:13,994 - DEBUG - VM ctest-pt_svm0-59131070 : Tap interface tape1456533-66 is set to Active 2025-12-18 02:14:13,994 - DEBUG - Tap interface tape1456533-66 detail : {'index': '7', 'name': 'tape1456533-66', 'uuid': 'e1456533-66a9-45ff-b94b-5fc184e8599b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447:ctest-right_vn-22716447', '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': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447', 'vm_uuid': '6d07c4d2-9ef0-4445-9cdc-6496495763a8', 'vm_name': 'ctest-pt_svm0-59131070', 'ip_addr': '128.128.71.132', 'mac_addr': '02:e1:45:65:33:66', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-60917613:e1456533-66a9-45ff-b94b-5fc184e8599b', 'sg_uuid_list': ['651428b1-c8bc-444f-91f7-f0433438c8bc'], 'static_route_list': None, 'vm_project_uuid': '166d041b-3807-4d2a-a782-e8a3a88d857f', 'admin_state': 'Enabled', 'flow_key_idx': '26', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': 'dfdec397-136d-4990-882e-f2ebb06d1181', '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-12-18 02:14:14,193 - DEBUG - Starting Layer 2 verification in Agent 2025-12-18 02:14:14,206 - DEBUG - Layer 2 path is seen for VM MAC 02:e1:45:65:33:66 in agent 10.0.0.141 2025-12-18 02:14:14,206 - DEBUG - Active layer 2 route in agent is present for VMI tape1456533-66 2025-12-18 02:14:14,206 - DEBUG - L2 label(53) matches bw route table and interface table 2025-12-18 02:14:14,214 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 is found in Agent of node 10.0.0.141 2025-12-18 02:14:14,433 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 is consistent in agent 10.0.0.141 2025-12-18 02:14:14,433 - DEBUG - Route for VM IP ['128.128.71.132'] is consistent in agent 10.0.0.141 2025-12-18 02:14:14,433 - DEBUG - VN default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 verification for VM ctest-pt_svm0-59131070 in Agent 10.0.0.141 passed 2025-12-18 02:14:14,633 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2025-12-18 02:14:14,653 - DEBUG - Route for VM MAC 02:e1:45:65:33:66 is consistent in agent 10.0.0.141 2025-12-18 02:14:14,655 - INFO - VM ctest-pt_svm0-59131070 verifications in Compute nodes passed 2025-12-18 02:14:14,655 - DEBUG - The SVMs in the SI are : [] 2025-12-18 02:14:14,655 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:14:14,667 - DEBUG - Service VM for SI 'ctest-si-63036939' is launched 2025-12-18 02:14:14,667 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:14,675 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:14,692 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:14,775 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8249e304-d5a3-4484-b5ca-07d73c3de1f9 2025-12-18 02:14:14,787 - DEBUG - SI ctest-si-63036939 has Port Tuple: ['ctest-port_tuple-29584419'] 2025-12-18 02:14:14,897 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-60917613:ctest-left_vn-20546113 2025-12-18 02:14:15,049 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-60917613:ctest-right_vn-22716447 2025-12-18 02:14:15,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,227 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,227 - INFO - Route to 128.128.71.131 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-4899-1, and SI ctest-si-63036939 is seen as the NH 2025-12-18 02:14:15,396 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,414 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,414 - INFO - Route to 201.23.247.67 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-4899-1, and SI ctest-si-63036939 is seen as the NH 2025-12-18 02:14:15,415 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,415 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-18 02:14:15,415 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 128.128.71.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-12-18 02:14:15,415 - DEBUG - ping -s 56 -c 3 -W 1 128.128.71.131 2025-12-18 02:14:19,602 - DEBUG - PING 128.128.71.131 (128.128.71.131): 56 data bytes 64 bytes from 128.128.71.131: seq=0 ttl=61 time=6.323 ms 64 bytes from 128.128.71.131: seq=1 ttl=61 time=2.388 ms 64 bytes from 128.128.71.131: seq=2 ttl=61 time=2.072 ms --- 128.128.71.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 2.072/3.594/6.323 ms 2025-12-18 02:14:19,603 - INFO - Ping to IP 128.128.71.131 from VM ctest-left_vm-77215068 passed 2025-12-18 02:14:19,603 - INFO - Dettaching policy ctest-policy-46919908 from vn ctest-right_vn-22716447 2025-12-18 02:14:19,829 - INFO - Dettaching policy ctest-policy-46919908 from vn ctest-left_vn-20546113 2025-12-18 02:14:20,133 - INFO - Deleted policy ctest-policy-46919908 2025-12-18 02:14:20,133 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-si-63036939'] 2025-12-18 02:14:20,237 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-60917613', 'ctest-si-63036939'] 2025-12-18 02:14:20,238 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:20,249 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:20,268 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:20,343 - DEBUG - Service instance ctest-si-63036939 removed from api server 2025-12-18 02:14:20,343 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6d07c4d2-9ef0-4445-9cdc-6496495763a8 2025-12-18 02:14:20,351 - DEBUG - All Service VMs unlinked from SI ctest-si-63036939 2025-12-18 02:14:20,357 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-18 02:14:20,357 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-18 02:14:20,408 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-18 02:14:20,409 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-18 02:14:20,409 - INFO - Deleting VM ctest-pt_svm0-59131070 2025-12-18 02:14:20,504 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-96798176'] 2025-12-18 02:14:20,600 - INFO - Service template: ['default-domain', 'ctest-service_template_1-96798176'] deleted successfully. 2025-12-18 02:14:20,601 - INFO - Deleting VM ctest-right_vm-67478651 2025-12-18 02:14:20,698 - INFO - Deleting VM ctest-left_vm-77215068 2025-12-18 02:14:20,796 - INFO - Deleting VN ctest-right_vn-22716447 2025-12-18 02:14:20,851 - DEBUG - VN fb1edc55-29b9-47b8-a184-7266827adccc still in use: Unable to complete operation on network fb1edc55-29b9-47b8-a184-7266827adccc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8bbae88e-5200-471c-9224-fa22cbbba3d9'] 2025-12-18 02:14:20,851 - WARNING - Deleting VN ctest-right_vn-22716447 failed..Will retry 2025-12-18 02:14:22,970 - DEBUG - Response for deleting network () 2025-12-18 02:14:22,970 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8239b465-f37a-46ed-a8ac-58b6bb9c3b02 2025-12-18 02:14:22,976 - DEBUG - Response Code: 404 2025-12-18 02:14:22,976 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:22,985 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:23,045 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:23,130 - INFO - Validated that VN ctest-right_vn-22716447 is not found in API Server 2025-12-18 02:14:23,141 - DEBUG - VN ctest-right_vn-22716447 is not present in Agent 10.0.0.141 2025-12-18 02:14:23,141 - INFO - Validated that VN ctest-right_vn-22716447 is not in any agent 2025-12-18 02:14:23,161 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-22716447 info 2025-12-18 02:14:23,161 - INFO - Deleting VN ctest-left_vn-20546113 2025-12-18 02:14:23,293 - DEBUG - Response for deleting network () 2025-12-18 02:14:23,293 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/71efad20-9c6c-493f-bb98-a8e191de3c2b 2025-12-18 02:14:23,299 - DEBUG - Response Code: 404 2025-12-18 02:14:23,299 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:23,305 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:23,358 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:23,442 - INFO - Validated that VN ctest-left_vn-20546113 is not found in API Server 2025-12-18 02:14:23,456 - DEBUG - VN ctest-left_vn-20546113 is not present in Agent 10.0.0.141 2025-12-18 02:14:23,456 - INFO - Validated that VN ctest-left_vn-20546113 is not in any agent 2025-12-18 02:14:23,477 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-20546113 info 2025-12-18 02:14:23,478 - INFO - Deleting VN ctest-mgmt_vn-46890528 2025-12-18 02:14:23,618 - DEBUG - Response for deleting network () 2025-12-18 02:14:23,618 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/863482be-c144-4d57-b254-a1bb04105fc3 2025-12-18 02:14:23,625 - DEBUG - Response Code: 404 2025-12-18 02:14:23,625 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2025-12-18 02:14:23,638 - DEBUG - Requesting: http://10.0.0.141:8082/domain/9b654697-e595-4d3f-a56f-4bba6d6b8abe 2025-12-18 02:14:23,695 - DEBUG - Requesting: http://10.0.0.141:8082/project/166d041b-3807-4d2a-a782-e8a3a88d857f 2025-12-18 02:14:23,775 - INFO - Validated that VN ctest-mgmt_vn-46890528 is not found in API Server 2025-12-18 02:14:23,785 - DEBUG - VN ctest-mgmt_vn-46890528 is not present in Agent 10.0.0.141 2025-12-18 02:14:23,785 - INFO - Validated that VN ctest-mgmt_vn-46890528 is not in any agent 2025-12-18 02:14:23,800 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-46890528 info 2025-12-18 02:14:24,069 - DEBUG - No XMPP flaps were noticed during the test 2025-12-18 02:14:24,069 - INFO -
2025-12-18 02:14:24,069 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:17]
2025-12-18 02:14:24,069 - INFO - -------------------------------------------------------------------------------- 2025-12-18 02:14:24,867 - INFO - Deleted project: ctest-TestSvcRegr-60917613, ID : 166d041b-3807-4d2a-a782-e8a3a88d857f