2025-12-04 03:28:49,685 - INFO - Domain Default found not creating 2025-12-04 03:28:49,867 - INFO - Project ctest-TestSVCV2Mirror-35129107 not found, creating it 2025-12-04 03:28:50,455 - INFO - Created Project:ctest-TestSVCV2Mirror-35129107, ID : b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:52,058 - INFO - ================================================================================ 2025-12-04 03:28:52,059 - INFO - STARTING TEST : test_svc_v2_mirroring 2025-12-04 03:28:52,059 - INFO - TEST DESCRIPTION : Validate the service chain mirroring 2025-12-04 03:28:53,365 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 03:28:53,365 - INFO - Initial checks done. Running the testcase now 2025-12-04 03:28:53,365 - INFO - 2025-12-04 03:28:54,188 - DEBUG - Response for create_network : {'network': {'id': '279338dc-ce69-4b84-b686-81188b710d2a', 'name': 'ctest-left-27321507', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-left-27321507'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:28:54,555 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cd86d18f-a9fe-497d-8d6b-5369201f370a', 'name': '', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'network_id': '279338dc-ce69-4b84-b686-81188b710d2a', 'ip_version': 4, 'cidr': '23.172.186.128/26', 'allocation_pools': [{'start': '23.172.186.130', 'end': '23.172.186.190'}], 'gateway_ip': '23.172.186.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23.172.186.130', 'tags': [], 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56'}} 2025-12-04 03:28:54,592 - INFO - Created VN ctest-left-27321507 2025-12-04 03:28:54,606 - DEBUG - VN ctest-left-27321507 UUID is 279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:54,684 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:55,010 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:55,051 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:55,068 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:55,174 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,195 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,211 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:28:55,224 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:28:55,231 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/a3963dea-095d-41f8-95e4-0865c7ec6d3a 2025-12-04 03:28:55,240 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 03:28:55,240 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,252 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:28:55,269 - INFO - Verified VN network id 13 for VN 279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,270 - INFO - Verifications in API Server for VN ctest-left-27321507 passed 2025-12-04 03:28:55,270 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,281 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:28:55,289 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:28:55,298 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/a3963dea-095d-41f8-95e4-0865c7ec6d3a 2025-12-04 03:28:55,313 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2851685510062623620', 'uuid-lslong': '13152341704200752426'}, 'enable': 'true', 'created': '2025-12-04T03:28:54', 'last-modified': '2025-12-04T03:28:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.826035'} 2025-12-04 03:28:55,321 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 03:28:55,328 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2851685510062623620', 'uuid-lslong': '13152341704200752426'}, 'enable': 'true', 'created': '2025-12-04T03:28:54', 'last-modified': '2025-12-04T03:28:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.840658'} 2025-12-04 03:28:55,335 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 03:28:55,341 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2851685510062623620', 'uuid-lslong': '13152341704200752426'}, 'enable': 'true', 'created': '2025-12-04T03:28:54', 'last-modified': '2025-12-04T03:28:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.849927'} 2025-12-04 03:28:55,347 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-04 03:28:55,347 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left-27321507 passed 2025-12-04 03:28:55,348 - DEBUG - ====Verifying policy data for ctest-left-27321507 in API_Server ====== 2025-12-04 03:28:55,348 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:55,356 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:55,370 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:55,466 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/279338dc-ce69-4b84-b686-81188b710d2a 2025-12-04 03:28:55,482 - DEBUG - =>VN ctest-left-27321507 has no policy to be verified 2025-12-04 03:28:55,482 - DEBUG - Verifying the vn in opserver 2025-12-04 03:28:55,482 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507 virtual network link through opserver 10.0.0.27 2025-12-04 03:28:55,482 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:28:55,818 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:28:55,893 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507?flat'} 2025-12-04 03:28:55,893 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507 virtual network link through opserver 10.0.0.33 2025-12-04 03:28:55,893 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:28:56,235 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:28:56,320 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507?flat'} 2025-12-04 03:28:56,320 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507 virtual network link through opserver 10.0.0.23 2025-12-04 03:28:56,320 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:28:56,634 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:28:56,710 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507?flat'} 2025-12-04 03:28:56,710 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507 is found in opserver 2025-12-04 03:28:56,737 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 03:28:56,751 - DEBUG - VRF ids for VN ctest-left-27321507: {} 2025-12-04 03:28:57,058 - DEBUG - Response for create_network : {'network': {'id': 'd189e90b-1673-40f3-bd90-47a9c5f55572', 'name': 'ctest-right-07358373', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-right-07358373'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:28:57,350 - DEBUG - Response for create_subnet : {'subnet': {'id': '698107ec-cc5e-4a81-83cb-591a27ccf64b', 'name': '', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'network_id': 'd189e90b-1673-40f3-bd90-47a9c5f55572', 'ip_version': 4, 'cidr': '70.238.117.64/26', 'allocation_pools': [{'start': '70.238.117.66', 'end': '70.238.117.126'}], 'gateway_ip': '70.238.117.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '70.238.117.66', 'tags': [], 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56'}} 2025-12-04 03:28:57,377 - INFO - Created VN ctest-right-07358373 2025-12-04 03:28:57,396 - DEBUG - VN ctest-right-07358373 UUID is d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,479 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:57,485 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:57,499 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:57,600 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,618 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,633 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:28:57,645 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:28:57,655 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4156a5c4-8ff2-4c40-89cf-384ff05fba0b 2025-12-04 03:28:57,665 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 03:28:57,665 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,679 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:28:57,702 - INFO - Verified VN network id 14 for VN d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,703 - INFO - Verifications in API Server for VN ctest-right-07358373 passed 2025-12-04 03:28:57,703 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,720 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:28:57,731 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:28:57,740 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/4156a5c4-8ff2-4c40-89cf-384ff05fba0b 2025-12-04 03:28:57,758 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15098855459566862579', 'uuid-lslong': '13659496464310949234'}, 'enable': 'true', 'created': '2025-12-04T03:28:56', 'last-modified': '2025-12-04T03:28:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.476629'} 2025-12-04 03:28:57,765 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 03:28:57,773 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15098855459566862579', 'uuid-lslong': '13659496464310949234'}, 'enable': 'true', 'created': '2025-12-04T03:28:56', 'last-modified': '2025-12-04T03:28:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.486130'} 2025-12-04 03:28:57,780 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 03:28:57,786 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15098855459566862579', 'uuid-lslong': '13659496464310949234'}, 'enable': 'true', 'created': '2025-12-04T03:28:56', 'last-modified': '2025-12-04T03:28:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504729'} 2025-12-04 03:28:57,798 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-12-04 03:28:57,798 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right-07358373 passed 2025-12-04 03:28:57,798 - DEBUG - ====Verifying policy data for ctest-right-07358373 in API_Server ====== 2025-12-04 03:28:57,798 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:57,806 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:57,820 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:57,916 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/d189e90b-1673-40f3-bd90-47a9c5f55572 2025-12-04 03:28:57,929 - DEBUG - =>VN ctest-right-07358373 has no policy to be verified 2025-12-04 03:28:57,929 - DEBUG - Verifying the vn in opserver 2025-12-04 03:28:57,929 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373 virtual network link through opserver 10.0.0.27 2025-12-04 03:28:57,929 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:28:57,993 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373?flat'} 2025-12-04 03:28:57,993 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373 virtual network link through opserver 10.0.0.33 2025-12-04 03:28:57,993 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:28:58,055 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373?flat'} 2025-12-04 03:28:58,055 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373 virtual network link through opserver 10.0.0.23 2025-12-04 03:28:58,055 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:28:58,140 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373?flat'} 2025-12-04 03:28:58,140 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373 is found in opserver 2025-12-04 03:28:58,165 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 03:28:58,179 - DEBUG - VRF ids for VN ctest-right-07358373: {} 2025-12-04 03:28:58,468 - DEBUG - Response for create_network : {'network': {'id': '71f89bb8-7da5-48cf-a985-e0b06e9a3244', 'name': 'ctest-mgmt_vn-67839566', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-mgmt_vn-67839566'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:28:58,706 - DEBUG - Response for create_subnet : {'subnet': {'id': '878e81b3-db33-4fbb-a0d3-eae7724f3917', 'name': '', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'network_id': '71f89bb8-7da5-48cf-a985-e0b06e9a3244', 'ip_version': 4, 'cidr': '111.50.206.192/26', 'allocation_pools': [{'start': '111.50.206.194', 'end': '111.50.206.254'}], 'gateway_ip': '111.50.206.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.50.206.194', 'tags': [], 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56'}} 2025-12-04 03:28:58,732 - INFO - Created VN ctest-mgmt_vn-67839566 2025-12-04 03:28:58,750 - DEBUG - VN ctest-mgmt_vn-67839566 UUID is 71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:58,840 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:58,846 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:58,860 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:58,962 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:58,977 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:58,988 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:28:58,996 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:28:59,004 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/02150701-c06e-495a-aaa2-1e9c72c7345a 2025-12-04 03:28:59,013 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 03:28:59,013 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:59,022 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:28:59,040 - INFO - Verified VN network id 15 for VN 71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:59,040 - INFO - Verifications in API Server for VN ctest-mgmt_vn-67839566 passed 2025-12-04 03:28:59,041 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:59,054 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:28:59,063 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:28:59,072 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/02150701-c06e-495a-aaa2-1e9c72c7345a 2025-12-04 03:28:59,087 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8212485137194371279', 'uuid-lslong': '12215416612686803524'}, 'enable': 'true', 'created': '2025-12-04T03:28:58', 'last-modified': '2025-12-04T03:28:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.464998'} 2025-12-04 03:28:59,094 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 03:28:59,099 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8212485137194371279', 'uuid-lslong': '12215416612686803524'}, 'enable': 'true', 'created': '2025-12-04T03:28:58', 'last-modified': '2025-12-04T03:28:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.478322'} 2025-12-04 03:28:59,106 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 03:28:59,112 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8212485137194371279', 'uuid-lslong': '12215416612686803524'}, 'enable': 'true', 'created': '2025-12-04T03:28:58', 'last-modified': '2025-12-04T03:28:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.491237'} 2025-12-04 03:28:59,118 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-04 03:28:59,119 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-67839566 passed 2025-12-04 03:28:59,119 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-67839566 in API_Server ====== 2025-12-04 03:28:59,119 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:28:59,133 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:28:59,145 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:28:59,254 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/71f89bb8-7da5-48cf-a985-e0b06e9a3244 2025-12-04 03:28:59,265 - DEBUG - =>VN ctest-mgmt_vn-67839566 has no policy to be verified 2025-12-04 03:28:59,265 - DEBUG - Verifying the vn in opserver 2025-12-04 03:28:59,265 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566 virtual network link through opserver 10.0.0.27 2025-12-04 03:28:59,266 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:28:59,341 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566?flat'} 2025-12-04 03:28:59,341 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566 virtual network link through opserver 10.0.0.33 2025-12-04 03:28:59,341 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:28:59,421 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566?flat'} 2025-12-04 03:28:59,421 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566 virtual network link through opserver 10.0.0.23 2025-12-04 03:28:59,421 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:28:59,489 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566?flat'} 2025-12-04 03:28:59,489 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-mgmt_vn-67839566 is found in opserver 2025-12-04 03:28:59,514 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 03:28:59,526 - DEBUG - VRF ids for VN ctest-mgmt_vn-67839566: {} 2025-12-04 03:28:59,830 - DEBUG - Response for create_network : {'network': {'id': 'e25eb029-2292-4d84-ac41-de5dd9d01139', 'name': 'ctest-trans_left_vn-27798771', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-trans_left_vn-27798771'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:29:00,152 - DEBUG - Response for create_subnet : {'subnet': {'id': '82c24e4b-562e-46d8-890b-e1a7b69e7e59', 'name': '', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'network_id': 'e25eb029-2292-4d84-ac41-de5dd9d01139', 'ip_version': 4, 'cidr': '214.141.24.128/26', 'allocation_pools': [{'start': '214.141.24.130', 'end': '214.141.24.190'}], 'gateway_ip': '214.141.24.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.141.24.130', 'tags': [], 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56'}} 2025-12-04 03:29:00,201 - INFO - Created VN ctest-trans_left_vn-27798771 2025-12-04 03:29:00,217 - DEBUG - VN ctest-trans_left_vn-27798771 UUID is e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,330 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:29:00,338 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:29:00,356 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:29:00,447 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,465 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,475 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:29:00,488 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:29:00,497 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/527f538b-06e2-4579-bbb6-48f6a5d244db 2025-12-04 03:29:00,504 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-04 03:29:00,504 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,514 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:29:00,531 - INFO - Verified VN network id 16 for VN e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,531 - INFO - Verifications in API Server for VN ctest-trans_left_vn-27798771 passed 2025-12-04 03:29:00,531 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,543 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:29:00,550 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:29:00,558 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/527f538b-06e2-4579-bbb6-48f6a5d244db 2025-12-04 03:29:00,570 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16311668591102676356', 'uuid-lslong': '12412446542677414201'}, 'enable': 'true', 'created': '2025-12-04T03:28:59', 'last-modified': '2025-12-04T03:29:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.484327'} 2025-12-04 03:29:00,576 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-04 03:29:00,581 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16311668591102676356', 'uuid-lslong': '12412446542677414201'}, 'enable': 'true', 'created': '2025-12-04T03:28:59', 'last-modified': '2025-12-04T03:29:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.500473'} 2025-12-04 03:29:00,588 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-04 03:29:00,593 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16311668591102676356', 'uuid-lslong': '12412446542677414201'}, 'enable': 'true', 'created': '2025-12-04T03:28:59', 'last-modified': '2025-12-04T03:29:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.512635'} 2025-12-04 03:29:00,600 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-04 03:29:00,600 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-27798771 passed 2025-12-04 03:29:00,600 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-27798771 in API_Server ====== 2025-12-04 03:29:00,600 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:29:00,607 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:29:00,623 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:29:00,740 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/e25eb029-2292-4d84-ac41-de5dd9d01139 2025-12-04 03:29:00,751 - DEBUG - =>VN ctest-trans_left_vn-27798771 has no policy to be verified 2025-12-04 03:29:00,752 - DEBUG - Verifying the vn in opserver 2025-12-04 03:29:00,752 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 virtual network link through opserver 10.0.0.27 2025-12-04 03:29:00,752 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:29:00,766 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771?flat'} 2025-12-04 03:29:00,766 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 virtual network link through opserver 10.0.0.33 2025-12-04 03:29:00,766 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:29:00,780 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771?flat'} 2025-12-04 03:29:00,780 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 virtual network link through opserver 10.0.0.23 2025-12-04 03:29:00,780 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:29:00,794 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771?flat'} 2025-12-04 03:29:00,794 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is found in opserver 2025-12-04 03:29:00,820 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 03:29:00,834 - DEBUG - VRF ids for VN ctest-trans_left_vn-27798771: {} 2025-12-04 03:29:01,123 - DEBUG - Response for create_network : {'network': {'id': '1f4b2842-becb-46a1-9b09-92b64d7291e9', 'name': 'ctest-trans_right_vn-22015848', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-trans_right_vn-22015848'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:29:01,356 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e304e38d-f4c0-4ce5-b3ed-093a5e1ed3c5', 'name': '', 'tenant_id': 'b6cc8bb8aa234b5baaa0583ccb62be56', 'network_id': '1f4b2842-becb-46a1-9b09-92b64d7291e9', 'ip_version': 4, 'cidr': '53.132.236.192/26', 'allocation_pools': [{'start': '53.132.236.194', 'end': '53.132.236.254'}], 'gateway_ip': '53.132.236.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.132.236.194', 'tags': [], 'project_id': 'b6cc8bb8aa234b5baaa0583ccb62be56'}} 2025-12-04 03:29:01,382 - INFO - Created VN ctest-trans_right_vn-22015848 2025-12-04 03:29:01,396 - DEBUG - VN ctest-trans_right_vn-22015848 UUID is 1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,480 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:29:01,487 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:29:01,502 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:29:01,618 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,635 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,646 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:29:01,655 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:29:01,664 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5d28a944-047b-4318-9a99-f621adfdf218 2025-12-04 03:29:01,672 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-04 03:29:01,672 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,683 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:29:01,702 - INFO - Verified VN network id 17 for VN 1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,702 - INFO - Verifications in API Server for VN ctest-trans_right_vn-22015848 passed 2025-12-04 03:29:01,702 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,713 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:29:01,721 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:29:01,730 - DEBUG - Requesting: http://10.0.0.27:8082/route-target/5d28a944-047b-4318-9a99-f621adfdf218 2025-12-04 03:29:01,745 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2254940305563010721', 'uuid-lslong': '11171621662350283241'}, 'enable': 'true', 'created': '2025-12-04T03:29:01', 'last-modified': '2025-12-04T03:29:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466513'} 2025-12-04 03:29:01,753 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-04 03:29:01,761 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2254940305563010721', 'uuid-lslong': '11171621662350283241'}, 'enable': 'true', 'created': '2025-12-04T03:29:01', 'last-modified': '2025-12-04T03:29:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.479888'} 2025-12-04 03:29:01,767 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-04 03:29:01,773 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-35129107', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2254940305563010721', 'uuid-lslong': '11171621662350283241'}, 'enable': 'true', 'created': '2025-12-04T03:29:01', 'last-modified': '2025-12-04T03:29:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.498812'} 2025-12-04 03:29:01,779 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-04 03:29:01,779 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-22015848 passed 2025-12-04 03:29:01,779 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-22015848 in API_Server ====== 2025-12-04 03:29:01,779 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:29:01,785 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:29:01,799 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:29:01,899 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-network/1f4b2842-becb-46a1-9b09-92b64d7291e9 2025-12-04 03:29:01,916 - DEBUG - =>VN ctest-trans_right_vn-22015848 has no policy to be verified 2025-12-04 03:29:01,917 - DEBUG - Verifying the vn in opserver 2025-12-04 03:29:01,917 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848 virtual network link through opserver 10.0.0.27 2025-12-04 03:29:01,917 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-04 03:29:01,930 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848?flat'} 2025-12-04 03:29:01,930 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848 virtual network link through opserver 10.0.0.33 2025-12-04 03:29:01,930 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-12-04 03:29:01,945 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848?flat'} 2025-12-04 03:29:01,945 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848 virtual network link through opserver 10.0.0.23 2025-12-04 03:29:01,945 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2025-12-04 03:29:01,970 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848?flat'} 2025-12-04 03:29:01,970 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_right_vn-22015848 is found in opserver 2025-12-04 03:29:01,994 - DEBUG - Do not have enough data to verify VN in agent 2025-12-04 03:29:02,009 - DEBUG - VRF ids for VN ctest-trans_right_vn-22015848: {} 2025-12-04 03:29:02,074 - DEBUG - Services list from nova: [, , , ] 2025-12-04 03:29:03,193 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 03:29:04,649 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-2) 2025-12-04 03:29:04,649 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-01042581'] 2025-12-04 03:29:04,944 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-01042581'] created succesfully 2025-12-04 03:29:06,471 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 03:29:06,471 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:29:06,805 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:29:06,846 - DEBUG - Response Code: 404 2025-12-04 03:29:08,847 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:29:08,864 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:29:08,957 - INFO - Waiting for VM ctest-pt_svm0-81553185 to be up.. 2025-12-04 03:29:09,057 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-04 03:29:14,158 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:29:14,158 - INFO - VM name : ctest-pt_svm0-81553185 2025-12-04 03:29:14,250 - DEBUG - VM ctest-pt_svm0-81553185 ID is 4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:29:14,278 - DEBUG - VM ctest-pt_svm0-81553185 launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 03:29:14,360 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:29:14,368 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:29:17,534 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-12-04 03:29:17,534 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:17,549 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:21,638 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-12-04 03:29:21,638 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:21,654 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:25,728 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-12-04 03:29:25,728 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:25,743 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:29,824 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-12-04 03:29:29,824 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:29,839 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:33,926 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2025-12-04 03:29:33,926 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:33,943 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:38,016 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-12-04 03:29:38,017 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:38,033 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:42,113 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-12-04 03:29:42,113 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:42,129 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:46,205 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-12-04 03:29:46,205 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:46,227 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:50,309 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-12-04 03:29:50,309 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:50,326 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:54,399 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-12-04 03:29:54,399 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:54,415 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:29:58,500 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms') 2025-12-04 03:29:58,500 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 failed! 2025-12-04 03:29:58,515 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is 214.141.24.129 and allocation pool is NOT set 2025-12-04 03:30:00,590 - 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=64 time=6.93 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=64 time=12.0 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 = 6.929/9.475/12.021/2.546 ms') 2025-12-04 03:30:00,590 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 passed 2025-12-04 03:30:00,662 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:00,662 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:00,735 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-12-04 03:30:00,823 - DEBUG - VM ctest-pt_svm0-81553185 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:30:05,824 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:05,824 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:05,890 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-12-04 03:30:05,987 - DEBUG - VM ctest-pt_svm0-81553185 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:30:10,987 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:10,987 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:11,064 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-12-04 03:30:11,225 - DEBUG - VM ctest-pt_svm0-81553185 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:30:16,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:16,225 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:16,284 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-12-04 03:30:16,379 - DEBUG - VM ctest-pt_svm0-81553185 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:30:21,380 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:21,380 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:21,449 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-12-04 03:30:21,539 - DEBUG - VM ctest-pt_svm0-81553185 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:30:26,539 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:26,540 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-81553185, IP 214.141.24.131, Port 22 2025-12-04 03:30:26,710 - DEBUG - VM ctest-pt_svm0-81553185 is ready for SSH connections 2025-12-04 03:30:26,717 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-si-47454140'] 2025-12-04 03:30:27,241 - INFO - Waiting for VM ctest-left_vm-09855888 to be up.. 2025-12-04 03:30:27,373 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:30:27,374 - INFO - VM name : ctest-left_vm-09855888 2025-12-04 03:30:27,518 - DEBUG - VM ctest-left_vm-09855888 ID is 104607b4-017e-49fe-8b70-ee78ffe33b3e 2025-12-04 03:30:27,518 - DEBUG - VM ctest-left_vm-09855888 launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 03:30:27,690 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/104607b4-017e-49fe-8b70-ee78ffe33b3e 2025-12-04 03:30:27,701 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/173ef74d-29b2-4c89-bc22-488df3ab9ca8 2025-12-04 03:30:28,875 - 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.89 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.794 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.794/2.340/3.886/1.546 ms') 2025-12-04 03:30:28,875 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-09855888 passed 2025-12-04 03:30:28,946 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:28,946 - DEBUG - Waiting to SSH to VM ctest-left_vm-09855888, IP 23.172.186.131, Port 22 2025-12-04 03:30:29,118 - DEBUG - VM ctest-left_vm-09855888 is ready for SSH connections 2025-12-04 03:30:29,118 - INFO - Waiting for VM ctest-right_vm-84125700 to be up.. 2025-12-04 03:30:29,213 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:30:29,213 - INFO - VM name : ctest-right_vm-84125700 2025-12-04 03:30:29,300 - DEBUG - VM ctest-right_vm-84125700 ID is a2f66d71-d91d-4466-9687-94a25073bb65 2025-12-04 03:30:29,300 - DEBUG - VM ctest-right_vm-84125700 launched on Node an-jenkins-deploy-platform-ansible-os-4840-2 2025-12-04 03:30:29,399 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a2f66d71-d91d-4466-9687-94a25073bb65 2025-12-04 03:30:29,410 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/d3232f0d-fa94-462c-9dd2-0fb57d5fc47a 2025-12-04 03:30:30,579 - 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.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.51 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 = 2.510/3.017/3.524/0.507 ms') 2025-12-04 03:30:30,580 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-right_vm-84125700 passed 2025-12-04 03:30:30,651 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:30,652 - DEBUG - Waiting to SSH to VM ctest-right_vm-84125700, IP 70.238.117.67, Port 22 2025-12-04 03:30:30,847 - DEBUG - VM ctest-right_vm-84125700 is ready for SSH connections 2025-12-04 03:30:30,853 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507, 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-TestSVCV2Mirror-35129107:ctest-right-07358373, 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 = [], service_properties = None, mirror_to = analyzer_name = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-si-47454140, encapsulation = None, analyzer_ip_address = None, analyzer_mac_address = None, routing_instance = None, udp_port = None, juniper_header = True, nh_mode = None, static_nh_header = None, nic_assisted_mirroring = False, nic_assisted_mirroring_vlan = 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-04 03:30:31,189 - INFO - Attaching policy ctest-policy-15115115 to vn ctest-left-27321507 2025-12-04 03:30:31,644 - INFO - Attaching policy ctest-policy-15115115 to vn ctest-right-07358373 2025-12-04 03:30:32,044 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-01042581'] created succesfully 2025-12-04 03:30:32,275 - DEBUG - Service instance: ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-si-47454140'] created succesfully 2025-12-04 03:30:32,275 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:30:32,282 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:30:32,296 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:30:32,400 - DEBUG - Requesting: http://10.0.0.27:8082/service-instance/573270f9-d886-4e0e-9740-43f9d1c67437 2025-12-04 03:30:32,412 - DEBUG - SI 'ctest-si-47454140' has service template ref to ctest-service_template_1-01042581 2025-12-04 03:30:32,412 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:30:32,421 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:30:32,436 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:30:32,548 - DEBUG - Requesting: http://10.0.0.27:8082/service-instance/573270f9-d886-4e0e-9740-43f9d1c67437 2025-12-04 03:30:32,568 - DEBUG - Requesting: http://10.0.0.27:8082/port-tuple/dede7a0c-565c-41df-924d-451b17b5cac7 2025-12-04 03:30:32,577 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:30:32,810 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:30:33,058 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:30:33,065 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:30:33,077 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/07612171-8cbe-41fc-93db-dfdcf3405f02 2025-12-04 03:30:33,086 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/5e534cb3-c239-4408-b3da-0eed5a3a1db2 2025-12-04 03:30:33,098 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/b521ce4a-4a2b-4ab0-aef8-b3f691f8f1cb 2025-12-04 03:30:33,301 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:30:33,302 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:30:33,310 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:30:34,467 - 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=64 time=4.36 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=64 time=1.64 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 = 1.635/2.998/4.361/1.363 ms') 2025-12-04 03:30:34,467 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-81553185 passed 2025-12-04 03:30:34,506 - DEBUG - VM ctest-pt_svm0-81553185 Tap interface: {'index': '4', 'name': 'tapb467d2c9-51', 'uuid': 'b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771:ctest-trans_left_vn-27798771', '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': '33', 'l2_label': '35', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'vm_uuid': '4d9ed3cc-935e-4f03-b45d-f7455742de46', 'vm_name': 'ctest-pt_svm0-81553185', 'ip_addr': '214.141.24.131', 'mac_addr': '02:b4:67:d2:c9:51', 'policy': 'Disable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '93', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7', 'sg_uuid_list': ['9166918e-6411-4db5-8541-b25fd656a908'], 'static_route_list': None, 'vm_project_uuid': 'b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56', 'admin_state': 'Enabled', 'flow_key_idx': '38', '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-04 03:30:34,506 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771:ctest-trans_left_vn-27798771 2025-12-04 03:30:34,513 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771:ctest-trans_left_vn-27798771', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.201:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-04 03:30:34,604 - DEBUG - VM ctest-pt_svm0-81553185 : Tap interface tapb467d2c9-51 is set to Active 2025-12-04 03:30:34,604 - DEBUG - Tap interface tapb467d2c9-51 detail : {'index': '4', 'name': 'tapb467d2c9-51', 'uuid': 'b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771:ctest-trans_left_vn-27798771', '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': '33', 'l2_label': '35', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771', 'vm_uuid': '4d9ed3cc-935e-4f03-b45d-f7455742de46', 'vm_name': 'ctest-pt_svm0-81553185', 'ip_addr': '214.141.24.131', 'mac_addr': '02:b4:67:d2:c9:51', 'policy': 'Disable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '93', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSVCV2Mirror-35129107:b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7', 'sg_uuid_list': ['9166918e-6411-4db5-8541-b25fd656a908'], 'static_route_list': None, 'vm_project_uuid': 'b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56', 'admin_state': 'Enabled', 'flow_key_idx': '38', '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-04 03:30:34,718 - DEBUG - Starting Layer 2 verification in Agent 2025-12-04 03:30:34,730 - DEBUG - Layer 2 path is seen for VM MAC 02:b4:67:d2:c9:51 in agent 10.0.0.22 2025-12-04 03:30:34,730 - DEBUG - Active layer 2 route in agent is present for VMI tapb467d2c9-51 2025-12-04 03:30:34,731 - DEBUG - L2 label(35) matches bw route table and interface table 2025-12-04 03:30:34,737 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is found in Agent of node 10.0.0.22 2025-12-04 03:30:34,874 - DEBUG - VRF IDs of VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 is consistent in agent 10.0.0.22 2025-12-04 03:30:34,875 - DEBUG - Route for VM IP ['214.141.24.131'] is consistent in agent 10.0.0.22 2025-12-04 03:30:34,875 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-trans_left_vn-27798771 verification for VM ctest-pt_svm0-81553185 in Agent 10.0.0.22 passed 2025-12-04 03:30:34,995 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22 2025-12-04 03:30:35,009 - DEBUG - Route for VM MAC 02:b4:67:d2:c9:51 is consistent in agent 10.0.0.22 2025-12-04 03:30:35,016 - INFO - VM ctest-pt_svm0-81553185 verifications in Compute nodes passed 2025-12-04 03:30:35,016 - DEBUG - The SVMs in the SI are : [] 2025-12-04 03:30:35,017 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:30:35,032 - DEBUG - Service VM for SI 'ctest-si-47454140' is launched 2025-12-04 03:30:35,032 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:30:35,049 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:30:35,062 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:30:35,183 - DEBUG - Requesting: http://10.0.0.27:8082/service-instance/573270f9-d886-4e0e-9740-43f9d1c67437 2025-12-04 03:30:35,195 - DEBUG - SI ctest-si-47454140 has Port Tuple: ['ctest-port_tuple-38812507'] 2025-12-04 03:30:35,261 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507 2025-12-04 03:30:35,369 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-35129107:ctest-right-07358373 2025-12-04 03:30:35,369 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:35,369 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:30:35,370 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 70.238.117.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 03:30:35,370 - DEBUG - ping -s 56 -c 3 -W 1 70.238.117.67 2025-12-04 03:31:31,940 - DEBUG - PING 70.238.117.67 (70.238.117.67) 56(84) bytes of data. 64 bytes from 70.238.117.67: icmp_req=1 ttl=63 time=9.95 ms 64 bytes from 70.238.117.67: icmp_req=2 ttl=63 time=1.66 ms 64 bytes from 70.238.117.67: icmp_req=3 ttl=63 time=1.49 ms --- 70.238.117.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.498/4.371/9.953/3.947 ms 2025-12-04 03:31:31,940 - INFO - Ping to IP 70.238.117.67 from VM ctest-left_vm-09855888 passed 2025-12-04 03:31:31,948 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507, 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-TestSVCV2Mirror-35129107:ctest-right-07358373, 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 = [], service_properties = None, mirror_to = analyzer_name = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-si-47454140, encapsulation = None, analyzer_ip_address = None, analyzer_mac_address = None, routing_instance = None, udp_port = None, juniper_header = True, nh_mode = None, static_nh_header = None, nic_assisted_mirroring = False, nic_assisted_mirroring_vlan = 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, rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp6, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-left-27321507, 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-TestSVCV2Mirror-35129107:ctest-right-07358373, 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 = [], service_properties = None, mirror_to = analyzer_name = default-domain:ctest-TestSVCV2Mirror-35129107:ctest-si-47454140, encapsulation = None, analyzer_ip_address = None, analyzer_mac_address = None, routing_instance = None, udp_port = None, juniper_header = True, nh_mode = None, static_nh_header = None, nic_assisted_mirroring = False, nic_assisted_mirroring_vlan = 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-04 03:31:32,021 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:31:32,021 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:31:32,029 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:31:32,044 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:31:32,150 - DEBUG - Requesting: http://10.0.0.27:8082/service-instance/573270f9-d886-4e0e-9740-43f9d1c67437 2025-12-04 03:31:32,160 - DEBUG - Requesting: http://10.0.0.27:8082/port-tuple/dede7a0c-565c-41df-924d-451b17b5cac7 2025-12-04 03:31:32,168 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:31:32,405 - DEBUG - Executing command: rm -f /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap 2025-12-04 03:31:42,834 - INFO - Starting tcpdump to capture the mirrored packets. 2025-12-04 03:31:42,834 - DEBUG - Executing command: sudo tcpdump -ni tapb467d2c9-51 -U udp port 8099 -w /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap 2025-12-04 03:31:52,857 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 70.238.117.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 03:31:52,857 - DEBUG - ping -s 56 -c 3 -W 1 70.238.117.67 2025-12-04 03:31:55,397 - DEBUG - PING 70.238.117.67 (70.238.117.67) 56(84) bytes of data. 64 bytes from 70.238.117.67: icmp_req=1 ttl=63 time=4.22 ms 64 bytes from 70.238.117.67: icmp_req=2 ttl=63 time=1.79 ms 64 bytes from 70.238.117.67: icmp_req=3 ttl=63 time=1.59 ms --- 70.238.117.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.597/2.538/4.222/1.193 ms 2025-12-04 03:31:55,397 - INFO - Ping to IP 70.238.117.67 from VM ctest-left_vm-09855888 passed 2025-12-04 03:31:55,397 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:31:55,405 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:31:55,460 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:31:55,554 - DEBUG - Requesting: http://10.0.0.27:8082/service-instance/573270f9-d886-4e0e-9740-43f9d1c67437 2025-12-04 03:31:55,573 - DEBUG - Requesting: http://10.0.0.27:8082/port-tuple/dede7a0c-565c-41df-924d-451b17b5cac7 2025-12-04 03:31:55,580 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b467d2c9-51c6-40b9-8eb8-8d5d37c06fb7 2025-12-04 03:31:55,707 - DEBUG - Waiting for the tcpdump write to complete. 2025-12-04 03:31:57,708 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap| awk '{print $2}') 2025-12-04 03:31:57,710 - DEBUG - Executing command: sync 2025-12-04 03:32:00,732 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap | wc -l 2025-12-04 03:32:00,747 - DEBUG - STDOUT: 12 2025-12-04 03:32:00,748 - DEBUG - STDERR: reading from file /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-12-04 03:32:00,748 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tapb467d2c9-51_ctest-random-96528609.pcap 2025-12-04 03:32:00,793 - INFO - 12 ICMP packets are mirrored to the analyzer service VM 'ctest-pt_svm0-81553185' 2025-12-04 03:32:00,793 - INFO - Dettaching policy ctest-policy-15115115 from vn ctest-right-07358373 2025-12-04 03:32:00,965 - INFO - Dettaching policy ctest-policy-15115115 from vn ctest-left-27321507 2025-12-04 03:32:01,313 - INFO - Deleted policy ctest-policy-15115115 2025-12-04 03:32:01,314 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-si-47454140'] 2025-12-04 03:32:01,450 - INFO - Deleted SI ['default-domain', 'ctest-TestSVCV2Mirror-35129107', 'ctest-si-47454140'] 2025-12-04 03:32:01,450 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:01,457 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:01,472 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:01,585 - DEBUG - Service instance ctest-si-47454140 removed from api server 2025-12-04 03:32:01,585 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/4d9ed3cc-935e-4f03-b45d-f7455742de46 2025-12-04 03:32:01,600 - DEBUG - All Service VMs unlinked from SI ctest-si-47454140 2025-12-04 03:32:01,610 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-04 03:32:01,610 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-04 03:32:01,661 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-04 03:32:01,661 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-04 03:32:01,661 - INFO - Deleting VM ctest-pt_svm0-81553185 2025-12-04 03:32:01,798 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-01042581'] 2025-12-04 03:32:01,885 - INFO - Service template: ['default-domain', 'ctest-service_template_1-01042581'] deleted successfully. 2025-12-04 03:32:01,885 - INFO - Deleting VM ctest-right_vm-84125700 2025-12-04 03:32:01,987 - INFO - Deleting VM ctest-left_vm-09855888 2025-12-04 03:32:02,081 - INFO - Deleting VN ctest-trans_right_vn-22015848 2025-12-04 03:32:02,310 - DEBUG - Response for deleting network () 2025-12-04 03:32:02,310 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/9f33eee8-a43c-414a-9c58-da1bc4d50bc2 2025-12-04 03:32:02,328 - DEBUG - Response Code: 404 2025-12-04 03:32:02,328 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:02,334 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:02,369 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:02,478 - INFO - Validated that VN ctest-trans_right_vn-22015848 is not found in API Server 2025-12-04 03:32:02,491 - DEBUG - VN ctest-trans_right_vn-22015848 is not present in Agent 10.0.0.22 2025-12-04 03:32:02,513 - DEBUG - VN ctest-trans_right_vn-22015848 is not present in Agent 10.0.0.130 2025-12-04 03:32:02,513 - INFO - Validated that VN ctest-trans_right_vn-22015848 is not in any agent 2025-12-04 03:32:02,620 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-22015848 info 2025-12-04 03:32:02,620 - INFO - Deleting VN ctest-trans_left_vn-27798771 2025-12-04 03:32:02,969 - DEBUG - Response for deleting network () 2025-12-04 03:32:02,969 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5ee40032-8eb1-4673-9b67-ad4ec1647b42 2025-12-04 03:32:02,980 - DEBUG - Response Code: 404 2025-12-04 03:32:02,981 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:02,993 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:03,012 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:03,156 - INFO - Validated that VN ctest-trans_left_vn-27798771 is not found in API Server 2025-12-04 03:32:03,172 - DEBUG - VN ctest-trans_left_vn-27798771 is not present in Agent 10.0.0.22 2025-12-04 03:32:03,206 - DEBUG - VN ctest-trans_left_vn-27798771 is not present in Agent 10.0.0.130 2025-12-04 03:32:03,207 - INFO - Validated that VN ctest-trans_left_vn-27798771 is not in any agent 2025-12-04 03:32:03,286 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-27798771 info 2025-12-04 03:32:03,289 - INFO - Deleting VN ctest-mgmt_vn-67839566 2025-12-04 03:32:03,503 - DEBUG - Response for deleting network () 2025-12-04 03:32:03,504 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/5f2fcab8-f9a5-4e44-bb59-5689feda4e00 2025-12-04 03:32:03,533 - DEBUG - Response Code: 404 2025-12-04 03:32:03,533 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:03,543 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:03,562 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:03,655 - INFO - Validated that VN ctest-mgmt_vn-67839566 is not found in API Server 2025-12-04 03:32:03,672 - DEBUG - VN ctest-mgmt_vn-67839566 is not present in Agent 10.0.0.22 2025-12-04 03:32:03,701 - DEBUG - VN ctest-mgmt_vn-67839566 is not present in Agent 10.0.0.130 2025-12-04 03:32:03,701 - INFO - Validated that VN ctest-mgmt_vn-67839566 is not in any agent 2025-12-04 03:32:03,777 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-67839566 info 2025-12-04 03:32:03,778 - INFO - Deleting VN ctest-right-07358373 2025-12-04 03:32:03,956 - DEBUG - Response for deleting network () 2025-12-04 03:32:03,956 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/ea9c2503-4264-4830-9d6b-1ed15fc92c75 2025-12-04 03:32:03,963 - DEBUG - Response Code: 404 2025-12-04 03:32:03,963 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:03,969 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:03,999 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:04,104 - INFO - Validated that VN ctest-right-07358373 is not found in API Server 2025-12-04 03:32:04,118 - DEBUG - VN ctest-right-07358373 is not present in Agent 10.0.0.22 2025-12-04 03:32:04,131 - DEBUG - VN ctest-right-07358373 is not present in Agent 10.0.0.130 2025-12-04 03:32:04,131 - INFO - Validated that VN ctest-right-07358373 is not in any agent 2025-12-04 03:32:04,164 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right-07358373 info 2025-12-04 03:32:04,164 - INFO - Deleting VN ctest-left-27321507 2025-12-04 03:32:04,325 - DEBUG - Response for deleting network () 2025-12-04 03:32:04,325 - DEBUG - Requesting: http://10.0.0.27:8082/routing-instance/6135cefa-7561-4444-8988-68317be22182 2025-12-04 03:32:04,330 - DEBUG - Response Code: 404 2025-12-04 03:32:04,330 - DEBUG - Requesting: http://10.0.0.27:8082/domains 2025-12-04 03:32:04,342 - DEBUG - Requesting: http://10.0.0.27:8082/domain/77f3106b-db9e-49b5-893e-09cac574b890 2025-12-04 03:32:04,357 - DEBUG - Requesting: http://10.0.0.27:8082/project/b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56 2025-12-04 03:32:04,452 - INFO - Validated that VN ctest-left-27321507 is not found in API Server 2025-12-04 03:32:04,468 - DEBUG - VN ctest-left-27321507 is not present in Agent 10.0.0.22 2025-12-04 03:32:04,481 - DEBUG - VN ctest-left-27321507 is not present in Agent 10.0.0.130 2025-12-04 03:32:04,481 - INFO - Validated that VN ctest-left-27321507 is not in any agent 2025-12-04 03:32:04,514 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left-27321507 info 2025-12-04 03:32:05,791 - DEBUG - No XMPP flaps were noticed during the test 2025-12-04 03:32:05,791 - INFO -

2025-12-04 03:32:05,791 - INFO - END TEST : test_svc_v2_mirroring : PASSED[0:03:13]

2025-12-04 03:32:05,791 - INFO - -------------------------------------------------------------------------------- 2025-12-04 03:32:06,585 - INFO - Deleted project: ctest-TestSVCV2Mirror-35129107, ID : b6cc8bb8-aa23-4b5b-aaa0-583ccb62be56