2026-04-06 03:31:38,955 - INFO - Domain Default found not creating 2026-04-06 03:31:39,132 - INFO - Project ctest-TestSVCV2Mirror-59554623 not found, creating it 2026-04-06 03:31:39,673 - INFO - Created Project:ctest-TestSVCV2Mirror-59554623, ID : d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:41,203 - INFO - ================================================================================ 2026-04-06 03:31:41,203 - INFO - STARTING TEST : test_svc_v2_mirroring 2026-04-06 03:31:41,203 - INFO - TEST DESCRIPTION : Validate the service chain mirroring 2026-04-06 03:31:42,491 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.129': '0'}, '10.0.0.241': {'10.20.0.18': '0', '10.20.0.129': '0'}} with 2026-04-06 03:31:42,491 - INFO - Initial checks done. Running the testcase now 2026-04-06 03:31:42,491 - INFO - 2026-04-06 03:31:43,270 - DEBUG - Response for create_network : {'network': {'id': '98ca24d9-c6f9-4227-bc18-d92a3bd48e5f', 'name': 'ctest-left-56628630', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-left-56628630'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 03:31:43,588 - DEBUG - Response for create_subnet : {'subnet': {'id': '5674d954-9902-474c-8259-000718a625e7', 'name': '', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'network_id': '98ca24d9-c6f9-4227-bc18-d92a3bd48e5f', 'ip_version': 4, 'cidr': '96.194.66.128/26', 'allocation_pools': [{'start': '96.194.66.130', 'end': '96.194.66.190'}], 'gateway_ip': '96.194.66.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.194.66.130', 'tags': [], 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4'}} 2026-04-06 03:31:43,614 - INFO - Created VN ctest-left-56628630 2026-04-06 03:31:43,628 - DEBUG - VN ctest-left-56628630 UUID is 98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:43,722 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:44,036 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:44,065 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:44,083 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:44,187 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,198 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,208 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:31:44,217 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:31:44,224 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/03f50cc9-c2d3-4101-839c-139b00933531 2026-04-06 03:31:44,230 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-04-06 03:31:44,230 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,241 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:31:44,258 - INFO - Verified VN network id 15 for VN 98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,258 - INFO - Verifications in API Server for VN ctest-left-56628630 passed 2026-04-06 03:31:44,259 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,273 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:31:44,284 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:31:44,292 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/03f50cc9-c2d3-4101-839c-139b00933531 2026-04-06 03:31:44,305 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11009652756825326119', 'uuid-lslong': '13553821853987147359'}, 'enable': 'true', 'created': '2026-04-06T03:31:43', 'last-modified': '2026-04-06T03:31:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.788305'} 2026-04-06 03:31:44,334 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-04-06 03:31:44,340 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11009652756825326119', 'uuid-lslong': '13553821853987147359'}, 'enable': 'true', 'created': '2026-04-06T03:31:43', 'last-modified': '2026-04-06T03:31:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.831253'} 2026-04-06 03:31:44,345 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-04-06 03:31:44,351 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11009652756825326119', 'uuid-lslong': '13553821853987147359'}, 'enable': 'true', 'created': '2026-04-06T03:31:43', 'last-modified': '2026-04-06T03:31:43', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.842499'} 2026-04-06 03:31:44,358 - DEBUG - Route Targets: ['target:64512:8000008'] 2026-04-06 03:31:44,358 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left-56628630 passed 2026-04-06 03:31:44,358 - DEBUG - ====Verifying policy data for ctest-left-56628630 in API_Server ====== 2026-04-06 03:31:44,358 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:44,365 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:44,377 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:44,475 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/98ca24d9-c6f9-4227-bc18-d92a3bd48e5f 2026-04-06 03:31:44,484 - DEBUG - =>VN ctest-left-56628630 has no policy to be verified 2026-04-06 03:31:44,484 - DEBUG - Verifying the vn in opserver 2026-04-06 03:31:44,484 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630 virtual network link through opserver 10.0.0.249 2026-04-06 03:31:44,484 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:44,811 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:44,873 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630?flat'} 2026-04-06 03:31:44,873 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630 virtual network link through opserver 10.0.0.27 2026-04-06 03:31:44,873 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:45,192 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:45,262 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630?flat'} 2026-04-06 03:31:45,262 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630 virtual network link through opserver 10.0.0.33 2026-04-06 03:31:45,262 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:45,564 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:45,638 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630?flat'} 2026-04-06 03:31:45,638 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630 is found in opserver 2026-04-06 03:31:45,662 - DEBUG - Do not have enough data to verify VN in agent 2026-04-06 03:31:45,674 - DEBUG - VRF ids for VN ctest-left-56628630: {} 2026-04-06 03:31:45,950 - DEBUG - Response for create_network : {'network': {'id': 'df89c94a-b347-4012-9c87-05ff182b7e2a', 'name': 'ctest-right-89658150', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-right-89658150'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 03:31:46,219 - DEBUG - Response for create_subnet : {'subnet': {'id': '0b443453-3425-4623-824c-e2f735ff12aa', 'name': '', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'network_id': 'df89c94a-b347-4012-9c87-05ff182b7e2a', 'ip_version': 4, 'cidr': '120.105.7.192/26', 'allocation_pools': [{'start': '120.105.7.194', 'end': '120.105.7.254'}], 'gateway_ip': '120.105.7.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.105.7.194', 'tags': [], 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4'}} 2026-04-06 03:31:46,260 - INFO - Created VN ctest-right-89658150 2026-04-06 03:31:46,272 - DEBUG - VN ctest-right-89658150 UUID is df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,361 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:46,367 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:46,384 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:46,474 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,500 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,510 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:31:46,521 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:31:46,536 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b40eddb0-2158-43be-897f-a168db82d68a 2026-04-06 03:31:46,545 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-04-06 03:31:46,545 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,561 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:31:46,578 - INFO - Verified VN network id 16 for VN df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,579 - INFO - Verifications in API Server for VN ctest-right-89658150 passed 2026-04-06 03:31:46,579 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,590 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:31:46,597 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:31:46,604 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b40eddb0-2158-43be-897f-a168db82d68a 2026-04-06 03:31:46,616 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16107626864939843602', 'uuid-lslong': '11278990384952999466'}, 'enable': 'true', 'created': '2026-04-06T03:31:45', 'last-modified': '2026-04-06T03:31:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.483128'} 2026-04-06 03:31:46,623 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-04-06 03:31:46,632 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16107626864939843602', 'uuid-lslong': '11278990384952999466'}, 'enable': 'true', 'created': '2026-04-06T03:31:45', 'last-modified': '2026-04-06T03:31:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.504121'} 2026-04-06 03:31:46,653 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-04-06 03:31:46,660 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16107626864939843602', 'uuid-lslong': '11278990384952999466'}, 'enable': 'true', 'created': '2026-04-06T03:31:45', 'last-modified': '2026-04-06T03:31:46', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.532645'} 2026-04-06 03:31:46,667 - DEBUG - Route Targets: ['target:64512:8000009'] 2026-04-06 03:31:46,667 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right-89658150 passed 2026-04-06 03:31:46,667 - DEBUG - ====Verifying policy data for ctest-right-89658150 in API_Server ====== 2026-04-06 03:31:46,667 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:46,674 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:46,685 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:46,794 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/df89c94a-b347-4012-9c87-05ff182b7e2a 2026-04-06 03:31:46,805 - DEBUG - =>VN ctest-right-89658150 has no policy to be verified 2026-04-06 03:31:46,805 - DEBUG - Verifying the vn in opserver 2026-04-06 03:31:46,805 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150 virtual network link through opserver 10.0.0.249 2026-04-06 03:31:46,805 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:46,880 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150?flat'} 2026-04-06 03:31:46,880 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150 virtual network link through opserver 10.0.0.27 2026-04-06 03:31:46,880 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:46,953 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150?flat'} 2026-04-06 03:31:46,953 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150 virtual network link through opserver 10.0.0.33 2026-04-06 03:31:46,953 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:47,033 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150?flat'} 2026-04-06 03:31:47,033 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150 is found in opserver 2026-04-06 03:31:47,067 - DEBUG - Do not have enough data to verify VN in agent 2026-04-06 03:31:47,086 - DEBUG - VRF ids for VN ctest-right-89658150: {} 2026-04-06 03:31:47,378 - DEBUG - Response for create_network : {'network': {'id': '3eab1966-5897-4985-8c59-f23332e32615', 'name': 'ctest-mgmt_vn-14166128', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-mgmt_vn-14166128'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 03:31:47,639 - DEBUG - Response for create_subnet : {'subnet': {'id': '03796445-fa45-4002-8e6e-0877b6b7d2c0', 'name': '', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'network_id': '3eab1966-5897-4985-8c59-f23332e32615', 'ip_version': 4, 'cidr': '7.23.57.64/26', 'allocation_pools': [{'start': '7.23.57.66', 'end': '7.23.57.126'}], 'gateway_ip': '7.23.57.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.23.57.66', 'tags': [], 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4'}} 2026-04-06 03:31:47,660 - INFO - Created VN ctest-mgmt_vn-14166128 2026-04-06 03:31:47,676 - DEBUG - VN ctest-mgmt_vn-14166128 UUID is 3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,758 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:47,765 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:47,777 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:47,872 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,890 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,906 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:31:47,917 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:31:47,924 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/c187a8a7-ec58-4946-ad5c-7704e9e763fd 2026-04-06 03:31:47,930 - DEBUG - Route Targets: ['target:64512:8000010'] 2026-04-06 03:31:47,930 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,943 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:31:47,959 - INFO - Verified VN network id 17 for VN 3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,959 - INFO - Verifications in API Server for VN ctest-mgmt_vn-14166128 passed 2026-04-06 03:31:47,959 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:47,969 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:31:47,975 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:31:47,990 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/c187a8a7-ec58-4946-ad5c-7704e9e763fd 2026-04-06 03:31:48,008 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4515730978732722565', 'uuid-lslong': '10113380739948160533'}, 'enable': 'true', 'created': '2026-04-06T03:31:47', 'last-modified': '2026-04-06T03:31:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.457637'} 2026-04-06 03:31:48,017 - DEBUG - Route Targets: ['target:64512:8000010'] 2026-04-06 03:31:48,022 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4515730978732722565', 'uuid-lslong': '10113380739948160533'}, 'enable': 'true', 'created': '2026-04-06T03:31:47', 'last-modified': '2026-04-06T03:31:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.449948'} 2026-04-06 03:31:48,028 - DEBUG - Route Targets: ['target:64512:8000010'] 2026-04-06 03:31:48,035 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4515730978732722565', 'uuid-lslong': '10113380739948160533'}, 'enable': 'true', 'created': '2026-04-06T03:31:47', 'last-modified': '2026-04-06T03:31:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.466110'} 2026-04-06 03:31:48,042 - DEBUG - Route Targets: ['target:64512:8000010'] 2026-04-06 03:31:48,042 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-14166128 passed 2026-04-06 03:31:48,042 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-14166128 in API_Server ====== 2026-04-06 03:31:48,042 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:48,051 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:48,062 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:48,146 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/3eab1966-5897-4985-8c59-f23332e32615 2026-04-06 03:31:48,159 - DEBUG - =>VN ctest-mgmt_vn-14166128 has no policy to be verified 2026-04-06 03:31:48,159 - DEBUG - Verifying the vn in opserver 2026-04-06 03:31:48,159 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128 virtual network link through opserver 10.0.0.249 2026-04-06 03:31:48,159 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:48,228 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128?flat'} 2026-04-06 03:31:48,228 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128 virtual network link through opserver 10.0.0.27 2026-04-06 03:31:48,228 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:48,297 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128?flat'} 2026-04-06 03:31:48,297 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128 virtual network link through opserver 10.0.0.33 2026-04-06 03:31:48,297 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:48,382 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128?flat'} 2026-04-06 03:31:48,382 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-mgmt_vn-14166128 is found in opserver 2026-04-06 03:31:48,402 - DEBUG - Do not have enough data to verify VN in agent 2026-04-06 03:31:48,423 - DEBUG - VRF ids for VN ctest-mgmt_vn-14166128: {} 2026-04-06 03:31:48,718 - DEBUG - Response for create_network : {'network': {'id': 'c5b06ba1-67f4-4908-a4ff-5d95592bb0fc', 'name': 'ctest-trans_left_vn-39954421', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-trans_left_vn-39954421'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 03:31:48,930 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bba74381-29b9-4fdf-abbe-0348be766420', 'name': '', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'network_id': 'c5b06ba1-67f4-4908-a4ff-5d95592bb0fc', 'ip_version': 4, 'cidr': '57.19.48.64/26', 'allocation_pools': [{'start': '57.19.48.66', 'end': '57.19.48.126'}], 'gateway_ip': '57.19.48.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '57.19.48.66', 'tags': [], 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4'}} 2026-04-06 03:31:48,954 - INFO - Created VN ctest-trans_left_vn-39954421 2026-04-06 03:31:48,969 - DEBUG - VN ctest-trans_left_vn-39954421 UUID is c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,065 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:49,073 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:49,085 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:49,173 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,184 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,192 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:31:49,199 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:31:49,206 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b456643d-9a7a-41ee-b6ce-dddb547553d4 2026-04-06 03:31:49,213 - DEBUG - Route Targets: ['target:64512:8000011'] 2026-04-06 03:31:49,213 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,221 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:31:49,244 - INFO - Verified VN network id 18 for VN c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,244 - INFO - Verifications in API Server for VN ctest-trans_left_vn-39954421 passed 2026-04-06 03:31:49,245 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,253 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:31:49,265 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:31:49,273 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/b456643d-9a7a-41ee-b6ce-dddb547553d4 2026-04-06 03:31:49,284 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14245003962350848264', 'uuid-lslong': '11889324437308944636'}, 'enable': 'true', 'created': '2026-04-06T03:31:48', 'last-modified': '2026-04-06T03:31:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.413408'} 2026-04-06 03:31:49,290 - DEBUG - Route Targets: ['target:64512:8000011'] 2026-04-06 03:31:49,296 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14245003962350848264', 'uuid-lslong': '11889324437308944636'}, 'enable': 'true', 'created': '2026-04-06T03:31:48', 'last-modified': '2026-04-06T03:31:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.423862'} 2026-04-06 03:31:49,302 - DEBUG - Route Targets: ['target:64512:8000011'] 2026-04-06 03:31:49,307 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14245003962350848264', 'uuid-lslong': '11889324437308944636'}, 'enable': 'true', 'created': '2026-04-06T03:31:48', 'last-modified': '2026-04-06T03:31:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435689'} 2026-04-06 03:31:49,315 - DEBUG - Route Targets: ['target:64512:8000011'] 2026-04-06 03:31:49,315 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-39954421 passed 2026-04-06 03:31:49,316 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-39954421 in API_Server ====== 2026-04-06 03:31:49,316 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:49,322 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:49,333 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:49,421 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/c5b06ba1-67f4-4908-a4ff-5d95592bb0fc 2026-04-06 03:31:49,430 - DEBUG - =>VN ctest-trans_left_vn-39954421 has no policy to be verified 2026-04-06 03:31:49,430 - DEBUG - Verifying the vn in opserver 2026-04-06 03:31:49,430 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 virtual network link through opserver 10.0.0.249 2026-04-06 03:31:49,430 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:49,442 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421?flat'} 2026-04-06 03:31:49,442 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 virtual network link through opserver 10.0.0.27 2026-04-06 03:31:49,442 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:49,455 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421?flat'} 2026-04-06 03:31:49,455 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 virtual network link through opserver 10.0.0.33 2026-04-06 03:31:49,455 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:49,468 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421?flat'} 2026-04-06 03:31:49,468 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is found in opserver 2026-04-06 03:31:49,487 - DEBUG - Do not have enough data to verify VN in agent 2026-04-06 03:31:49,496 - DEBUG - VRF ids for VN ctest-trans_left_vn-39954421: {} 2026-04-06 03:31:49,778 - DEBUG - Response for create_network : {'network': {'id': '4f9c3b0d-7e0b-430f-900c-e2e5119a2f85', 'name': 'ctest-trans_right_vn-43115504', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-trans_right_vn-43115504'], 'port_security_enabled': True, 'description': ''}} 2026-04-06 03:31:50,043 - DEBUG - Response for create_subnet : {'subnet': {'id': '2e589ff8-ea31-4e6b-9d4a-1da8c751e653', 'name': '', 'tenant_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4', 'network_id': '4f9c3b0d-7e0b-430f-900c-e2e5119a2f85', 'ip_version': 4, 'cidr': '15.107.157.0/26', 'allocation_pools': [{'start': '15.107.157.2', 'end': '15.107.157.62'}], 'gateway_ip': '15.107.157.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.107.157.2', 'tags': [], 'project_id': 'd9ddd5e8c1b641fe9f93a34ae8e75bd4'}} 2026-04-06 03:31:50,067 - INFO - Created VN ctest-trans_right_vn-43115504 2026-04-06 03:31:50,079 - DEBUG - VN ctest-trans_right_vn-43115504 UUID is 4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,160 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:50,167 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:50,181 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:50,265 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,278 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,286 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:31:50,294 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:31:50,299 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/244487e4-eafe-4adb-82e5-d0a2d15427a4 2026-04-06 03:31:50,305 - DEBUG - Route Targets: ['target:64512:8000012'] 2026-04-06 03:31:50,305 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,313 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:31:50,327 - INFO - Verified VN network id 19 for VN 4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,327 - INFO - Verifications in API Server for VN ctest-trans_right_vn-43115504 passed 2026-04-06 03:31:50,327 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,336 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:31:50,343 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:31:50,349 - DEBUG - Requesting: http://10.0.0.249:8082/route-target/244487e4-eafe-4adb-82e5-d0a2d15427a4 2026-04-06 03:31:50,361 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5736524954498450191', 'uuid-lslong': '10379920714652856197'}, 'enable': 'true', 'created': '2026-04-06T03:31:49', 'last-modified': '2026-04-06T03:31:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.365807'} 2026-04-06 03:31:50,367 - DEBUG - Route Targets: ['target:64512:8000012'] 2026-04-06 03:31:50,373 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5736524954498450191', 'uuid-lslong': '10379920714652856197'}, 'enable': 'true', 'created': '2026-04-06T03:31:49', 'last-modified': '2026-04-06T03:31:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.373877'} 2026-04-06 03:31:50,378 - DEBUG - Route Targets: ['target:64512:8000012'] 2026-04-06 03:31:50,385 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-59554623', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5736524954498450191', 'uuid-lslong': '10379920714652856197'}, 'enable': 'true', 'created': '2026-04-06T03:31:49', 'last-modified': '2026-04-06T03:31:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.380316'} 2026-04-06 03:31:50,393 - DEBUG - Route Targets: ['target:64512:8000012'] 2026-04-06 03:31:50,393 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-43115504 passed 2026-04-06 03:31:50,393 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-43115504 in API_Server ====== 2026-04-06 03:31:50,393 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:31:50,399 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:31:50,409 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:31:50,502 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-network/4f9c3b0d-7e0b-430f-900c-e2e5119a2f85 2026-04-06 03:31:50,511 - DEBUG - =>VN ctest-trans_right_vn-43115504 has no policy to be verified 2026-04-06 03:31:50,511 - DEBUG - Verifying the vn in opserver 2026-04-06 03:31:50,511 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504 virtual network link through opserver 10.0.0.249 2026-04-06 03:31:50,511 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-04-06 03:31:50,530 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504?flat'} 2026-04-06 03:31:50,530 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504 virtual network link through opserver 10.0.0.27 2026-04-06 03:31:50,530 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-04-06 03:31:50,543 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504?flat'} 2026-04-06 03:31:50,543 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504 virtual network link through opserver 10.0.0.33 2026-04-06 03:31:50,543 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-04-06 03:31:50,556 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504?flat'} 2026-04-06 03:31:50,556 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_right_vn-43115504 is found in opserver 2026-04-06 03:31:50,586 - DEBUG - Do not have enough data to verify VN in agent 2026-04-06 03:31:50,597 - DEBUG - VRF ids for VN ctest-trans_right_vn-43115504: {} 2026-04-06 03:31:50,740 - DEBUG - Services list from nova: [, , , ] 2026-04-06 03:31:51,852 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 03:31:53,315 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-1) 2026-04-06 03:31:53,316 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92946209'] 2026-04-06 03:31:53,623 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92946209'] created succesfully 2026-04-06 03:31:55,164 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5596-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5596-2) 2026-04-06 03:31:55,165 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:31:55,504 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:31:57,583 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:31:57,597 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:31:57,687 - INFO - Waiting for VM ctest-pt_svm0-89633777 to be up.. 2026-04-06 03:31:57,778 - DEBUG - VM is in ACTIVE state now 2026-04-06 03:31:57,778 - INFO - VM name : ctest-pt_svm0-89633777 2026-04-06 03:31:57,855 - DEBUG - VM ctest-pt_svm0-89633777 ID is 8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:31:57,882 - DEBUG - VM ctest-pt_svm0-89633777 launched on Node an-jenkins-deploy-platform-ansible-os-5596-2 2026-04-06 03:31:57,976 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:31:57,984 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:32:01,184 - 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 1017ms') 2026-04-06 03:32:01,184 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:01,293 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:05,375 - 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') 2026-04-06 03:32:05,375 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:05,392 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:09,471 - 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 1016ms') 2026-04-06 03:32:09,471 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:09,487 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:13,561 - 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') 2026-04-06 03:32:13,561 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:13,577 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:17,660 - 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 1023ms') 2026-04-06 03:32:17,660 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:17,679 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:21,762 - 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 1019ms') 2026-04-06 03:32:21,762 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:21,777 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:25,849 - 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 1016ms') 2026-04-06 03:32:25,849 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:25,863 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:29,944 - 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 1021ms') 2026-04-06 03:32:29,944 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:29,960 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:34,049 - 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 1023ms') 2026-04-06 03:32:34,049 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:34,069 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:38,139 - 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 1011ms') 2026-04-06 03:32:38,139 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 failed! 2026-04-06 03:32:38,153 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is 57.19.48.65 and allocation pool is NOT set 2026-04-06 03:32:42,241 - 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=2 ttl=64 time=22.2 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1023ms\r\nrtt min/avg/max/mdev = 22.186/22.186/22.186/0.000 ms') 2026-04-06 03:32:42,241 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 passed 2026-04-06 03:32:42,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:32:42,308 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89633777, IP 57.19.48.67, Port 22 2026-04-06 03:32:42,376 - DEBUG - Error on ssh to ubuntu@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': ''} 2026-04-06 03:32:42,458 - DEBUG - VM ctest-pt_svm0-89633777 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-06 03:32:47,459 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:32:47,459 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89633777, IP 57.19.48.67, Port 22 2026-04-06 03:32:47,525 - DEBUG - Error on ssh to ubuntu@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': ''} 2026-04-06 03:32:47,609 - DEBUG - VM ctest-pt_svm0-89633777 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-06 03:32:52,610 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:32:52,610 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89633777, IP 57.19.48.67, Port 22 2026-04-06 03:32:52,677 - DEBUG - Error on ssh to ubuntu@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': ''} 2026-04-06 03:32:52,786 - DEBUG - VM ctest-pt_svm0-89633777 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-06 03:32:57,787 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:32:57,787 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89633777, IP 57.19.48.67, Port 22 2026-04-06 03:32:57,843 - DEBUG - Error on ssh to ubuntu@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': ''} 2026-04-06 03:32:57,964 - DEBUG - VM ctest-pt_svm0-89633777 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-06 03:33:02,964 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:02,964 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89633777, IP 57.19.48.67, Port 22 2026-04-06 03:33:03,133 - DEBUG - VM ctest-pt_svm0-89633777 is ready for SSH connections 2026-04-06 03:33:03,139 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-si-58773700'] 2026-04-06 03:33:03,655 - INFO - Waiting for VM ctest-left_vm-66799328 to be up.. 2026-04-06 03:33:03,781 - DEBUG - VM is in ACTIVE state now 2026-04-06 03:33:03,781 - INFO - VM name : ctest-left_vm-66799328 2026-04-06 03:33:03,934 - DEBUG - VM ctest-left_vm-66799328 ID is 171c02d8-5243-453e-b49f-3832520b78c1 2026-04-06 03:33:03,935 - DEBUG - VM ctest-left_vm-66799328 launched on Node an-jenkins-deploy-platform-ansible-os-5596-2 2026-04-06 03:33:04,049 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/171c02d8-5243-453e-b49f-3832520b78c1 2026-04-06 03:33:04,077 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/e089b44a-79ae-4cbd-afcc-97972817ed5e 2026-04-06 03:33:05,236 - 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.18 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.55 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 2.546/2.860/3.175/0.314 ms') 2026-04-06 03:33:05,236 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-left_vm-66799328 passed 2026-04-06 03:33:05,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:05,308 - DEBUG - Waiting to SSH to VM ctest-left_vm-66799328, IP 96.194.66.131, Port 22 2026-04-06 03:33:05,463 - DEBUG - VM ctest-left_vm-66799328 is ready for SSH connections 2026-04-06 03:33:05,463 - INFO - Waiting for VM ctest-right_vm-70502605 to be up.. 2026-04-06 03:33:05,557 - DEBUG - VM is in ACTIVE state now 2026-04-06 03:33:05,557 - INFO - VM name : ctest-right_vm-70502605 2026-04-06 03:33:05,650 - DEBUG - VM ctest-right_vm-70502605 ID is b73f871d-a5dc-4560-8123-bee1f582cde8 2026-04-06 03:33:05,651 - DEBUG - VM ctest-right_vm-70502605 launched on Node an-jenkins-deploy-platform-ansible-os-5596-1 2026-04-06 03:33:05,745 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/b73f871d-a5dc-4560-8123-bee1f582cde8 2026-04-06 03:33:05,765 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/76b815bb-7e39-4c5c-bebf-5a914c92e75e 2026-04-06 03:33:06,934 - 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.61 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.40 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.404/2.505/3.606/1.101 ms') 2026-04-06 03:33:06,935 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-70502605 passed 2026-04-06 03:33:07,006 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:07,006 - DEBUG - Waiting to SSH to VM ctest-right_vm-70502605, IP 120.105.7.195, Port 22 2026-04-06 03:33:07,173 - DEBUG - VM ctest-right_vm-70502605 is ready for SSH connections 2026-04-06 03:33:07,180 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630, 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-59554623:ctest-right-89658150, 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-59554623:ctest-si-58773700, 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] 2026-04-06 03:33:07,531 - INFO - Attaching policy ctest-policy-46595483 to vn ctest-left-56628630 2026-04-06 03:33:07,946 - INFO - Attaching policy ctest-policy-46595483 to vn ctest-right-89658150 2026-04-06 03:33:08,310 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92946209'] created succesfully 2026-04-06 03:33:08,523 - DEBUG - Service instance: ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-si-58773700'] created succesfully 2026-04-06 03:33:08,524 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:33:08,529 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:33:08,543 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:33:08,653 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/c084ffe6-a066-4eef-b63d-6d51e63a457d 2026-04-06 03:33:08,660 - DEBUG - SI 'ctest-si-58773700' has service template ref to ctest-service_template_1-92946209 2026-04-06 03:33:08,661 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:33:08,666 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:33:08,676 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:33:08,772 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/c084ffe6-a066-4eef-b63d-6d51e63a457d 2026-04-06 03:33:08,781 - DEBUG - Requesting: http://10.0.0.249:8082/port-tuple/aafd26d4-4c61-49fd-a1d9-68bc65f7f6fb 2026-04-06 03:33:08,788 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:33:09,000 - DEBUG - VM is in ACTIVE state now 2026-04-06 03:33:09,279 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:33:09,287 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:33:09,297 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/69246b5c-0808-4336-9991-d8953f05e255 2026-04-06 03:33:09,304 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/79870da5-4f08-417e-b742-e7c41b4fe0a5 2026-04-06 03:33:09,311 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/a3ac6b89-6557-4c22-995a-a835fe42e7bc 2026-04-06 03:33:09,497 - DEBUG - VM is in ACTIVE state now 2026-04-06 03:33:09,497 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:33:09,509 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:33:10,667 - 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=64 time=2.30 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=64 time=2.00 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.996/2.150/2.304/0.154 ms') 2026-04-06 03:33:10,667 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89633777 passed 2026-04-06 03:33:10,704 - DEBUG - VM ctest-pt_svm0-89633777 Tap interface: {'index': '5', 'name': 'tap931a9abe-63', 'uuid': '931a9abe-6359-41f4-b334-13c0147507e9', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421:ctest-trans_left_vn-39954421', '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': '40', 'l2_label': '42', 'vxlan_id': '18', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'vm_uuid': '8fe36cb2-c3be-4387-aae0-d61ed6c3e913', 'vm_name': 'ctest-pt_svm0-89633777', 'ip_addr': '57.19.48.67', 'mac_addr': '02:93:1a:9a:be:63', 'policy': 'Disable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '84', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:931a9abe-6359-41f4-b334-13c0147507e9', 'sg_uuid_list': ['652b86cd-15af-4330-a05a-edfd65c924f0'], 'static_route_list': None, 'vm_project_uuid': 'd9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4', 'admin_state': 'Enabled', 'flow_key_idx': '49', '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': []} 2026-04-06 03:33:10,705 - DEBUG - Agent 10.0.0.241 vrf name: default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421:ctest-trans_left_vn-39954421 2026-04-06 03:33:10,709 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421:ctest-trans_left_vn-39954421', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'table_label': '-1', 'vxlan_id': '18', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.201:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-06 03:33:10,790 - DEBUG - VM ctest-pt_svm0-89633777 : Tap interface tap931a9abe-63 is set to Active 2026-04-06 03:33:10,790 - DEBUG - Tap interface tap931a9abe-63 detail : {'index': '5', 'name': 'tap931a9abe-63', 'uuid': '931a9abe-6359-41f4-b334-13c0147507e9', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421:ctest-trans_left_vn-39954421', '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': '40', 'l2_label': '42', 'vxlan_id': '18', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421', 'vm_uuid': '8fe36cb2-c3be-4387-aae0-d61ed6c3e913', 'vm_name': 'ctest-pt_svm0-89633777', 'ip_addr': '57.19.48.67', 'mac_addr': '02:93:1a:9a:be:63', 'policy': 'Disable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '84', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSVCV2Mirror-59554623:931a9abe-6359-41f4-b334-13c0147507e9', 'sg_uuid_list': ['652b86cd-15af-4330-a05a-edfd65c924f0'], 'static_route_list': None, 'vm_project_uuid': 'd9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4', 'admin_state': 'Enabled', 'flow_key_idx': '49', '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': []} 2026-04-06 03:33:10,905 - DEBUG - Starting Layer 2 verification in Agent 2026-04-06 03:33:10,919 - DEBUG - Layer 2 path is seen for VM MAC 02:93:1a:9a:be:63 in agent 10.0.0.241 2026-04-06 03:33:10,920 - DEBUG - Active layer 2 route in agent is present for VMI tap931a9abe-63 2026-04-06 03:33:10,920 - DEBUG - L2 label(42) matches bw route table and interface table 2026-04-06 03:33:10,937 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is found in Agent of node 10.0.0.241 2026-04-06 03:33:11,071 - DEBUG - VRF IDs of VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 is consistent in agent 10.0.0.241 2026-04-06 03:33:11,071 - DEBUG - Route for VM IP ['57.19.48.67'] is consistent in agent 10.0.0.241 2026-04-06 03:33:11,072 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-trans_left_vn-39954421 verification for VM ctest-pt_svm0-89633777 in Agent 10.0.0.241 passed 2026-04-06 03:33:11,194 - DEBUG - Starting all layer 2 verification in agent 10.0.0.241 2026-04-06 03:33:11,215 - DEBUG - Route for VM MAC 02:93:1a:9a:be:63 is consistent in agent 10.0.0.241 2026-04-06 03:33:11,215 - INFO - VM ctest-pt_svm0-89633777 verifications in Compute nodes passed 2026-04-06 03:33:11,215 - DEBUG - The SVMs in the SI are : [] 2026-04-06 03:33:11,215 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:33:11,226 - DEBUG - Service VM for SI 'ctest-si-58773700' is launched 2026-04-06 03:33:11,226 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:33:11,232 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:33:11,242 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:33:11,334 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/c084ffe6-a066-4eef-b63d-6d51e63a457d 2026-04-06 03:33:11,342 - DEBUG - SI ctest-si-58773700 has Port Tuple: ['ctest-port_tuple-64587506'] 2026-04-06 03:33:11,407 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630 2026-04-06 03:33:11,515 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-59554623:ctest-right-89658150 2026-04-06 03:33:11,515 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:11,515 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:11,516 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.105.7.195, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2026-04-06 03:33:11,516 - DEBUG - ping -s 56 -c 3 -W 1 120.105.7.195 2026-04-06 03:33:57,018 - DEBUG - PING 120.105.7.195 (120.105.7.195) 56(84) bytes of data. 64 bytes from 120.105.7.195: icmp_req=1 ttl=63 time=9.24 ms 64 bytes from 120.105.7.195: icmp_req=2 ttl=63 time=1.37 ms 64 bytes from 120.105.7.195: icmp_req=3 ttl=63 time=1.78 ms --- 120.105.7.195 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.374/4.134/9.241/3.615 ms 2026-04-06 03:33:57,018 - INFO - Ping to IP 120.105.7.195 from VM ctest-left_vm-66799328 passed 2026-04-06 03:33:57,027 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-59554623:ctest-left-56628630, 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-59554623:ctest-right-89658150, 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-59554623:ctest-si-58773700, 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-59554623:ctest-left-56628630, 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-59554623:ctest-right-89658150, 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-59554623:ctest-si-58773700, 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] 2026-04-06 03:33:57,099 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-06 03:33:57,099 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:33:57,107 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:33:57,169 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:33:57,272 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/c084ffe6-a066-4eef-b63d-6d51e63a457d 2026-04-06 03:33:57,283 - DEBUG - Requesting: http://10.0.0.249:8082/port-tuple/aafd26d4-4c61-49fd-a1d9-68bc65f7f6fb 2026-04-06 03:33:57,294 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:33:57,483 - DEBUG - Executing command: rm -f /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap 2026-04-06 03:34:07,899 - INFO - Starting tcpdump to capture the mirrored packets. 2026-04-06 03:34:07,899 - DEBUG - Executing command: sudo tcpdump -ni tap931a9abe-63 -U udp port 8099 -w /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap 2026-04-06 03:34:17,932 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.105.7.195, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2026-04-06 03:34:17,933 - DEBUG - ping -s 56 -c 3 -W 1 120.105.7.195 2026-04-06 03:34:20,397 - DEBUG - PING 120.105.7.195 (120.105.7.195) 56(84) bytes of data. 64 bytes from 120.105.7.195: icmp_req=1 ttl=63 time=5.26 ms 64 bytes from 120.105.7.195: icmp_req=2 ttl=63 time=1.41 ms 64 bytes from 120.105.7.195: icmp_req=3 ttl=63 time=1.25 ms --- 120.105.7.195 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.251/2.643/5.266/1.856 ms 2026-04-06 03:34:20,397 - INFO - Ping to IP 120.105.7.195 from VM ctest-left_vm-66799328 passed 2026-04-06 03:34:20,397 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:20,408 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:20,421 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:20,541 - DEBUG - Requesting: http://10.0.0.249:8082/service-instance/c084ffe6-a066-4eef-b63d-6d51e63a457d 2026-04-06 03:34:20,553 - DEBUG - Requesting: http://10.0.0.249:8082/port-tuple/aafd26d4-4c61-49fd-a1d9-68bc65f7f6fb 2026-04-06 03:34:20,563 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/931a9abe-6359-41f4-b334-13c0147507e9 2026-04-06 03:34:20,689 - DEBUG - Waiting for the tcpdump write to complete. 2026-04-06 03:34:22,690 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap| awk '{print $2}') 2026-04-06 03:34:22,692 - DEBUG - Executing command: sync 2026-04-06 03:34:25,716 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap | wc -l 2026-04-06 03:34:25,733 - DEBUG - STDOUT: 12 2026-04-06 03:34:25,733 - DEBUG - STDERR: reading from file /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2026-04-06 03:34:25,733 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tap931a9abe-63_ctest-random-32676134.pcap 2026-04-06 03:34:25,776 - INFO - 12 ICMP packets are mirrored to the analyzer service VM 'ctest-pt_svm0-89633777' 2026-04-06 03:34:25,776 - INFO - Dettaching policy ctest-policy-46595483 from vn ctest-right-89658150 2026-04-06 03:34:25,918 - INFO - Dettaching policy ctest-policy-46595483 from vn ctest-left-56628630 2026-04-06 03:34:26,158 - INFO - Deleted policy ctest-policy-46595483 2026-04-06 03:34:26,158 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-si-58773700'] 2026-04-06 03:34:26,272 - INFO - Deleted SI ['default-domain', 'ctest-TestSVCV2Mirror-59554623', 'ctest-si-58773700'] 2026-04-06 03:34:26,272 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:26,280 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:26,292 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:26,395 - DEBUG - Service instance ctest-si-58773700 removed from api server 2026-04-06 03:34:26,395 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/8fe36cb2-c3be-4387-aae0-d61ed6c3e913 2026-04-06 03:34:26,417 - DEBUG - All Service VMs unlinked from SI ctest-si-58773700 2026-04-06 03:34:26,429 - DEBUG - 0 svc intances found in all projects. They are [] 2026-04-06 03:34:26,429 - DEBUG - 0 svc intances found in current project. They are [] 2026-04-06 03:34:26,483 - DEBUG - 0 svc intances found in all projects. They are [] 2026-04-06 03:34:26,483 - DEBUG - 0 svc intances found in current project. They are [] 2026-04-06 03:34:26,483 - INFO - Deleting VM ctest-pt_svm0-89633777 2026-04-06 03:34:26,610 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92946209'] 2026-04-06 03:34:26,735 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92946209'] deleted successfully. 2026-04-06 03:34:26,735 - INFO - Deleting VM ctest-right_vm-70502605 2026-04-06 03:34:26,849 - INFO - Deleting VM ctest-left_vm-66799328 2026-04-06 03:34:26,949 - INFO - Deleting VN ctest-trans_right_vn-43115504 2026-04-06 03:34:27,084 - DEBUG - Response for deleting network () 2026-04-06 03:34:27,084 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/35b24449-99b4-4074-abd5-5cdcfa1b955c 2026-04-06 03:34:27,092 - DEBUG - Response Code: 404 2026-04-06 03:34:27,092 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:27,097 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:27,157 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:27,255 - INFO - Validated that VN ctest-trans_right_vn-43115504 is not found in API Server 2026-04-06 03:34:27,271 - DEBUG - VN ctest-trans_right_vn-43115504 is not present in Agent 10.0.0.19 2026-04-06 03:34:27,295 - DEBUG - VN ctest-trans_right_vn-43115504 is not present in Agent 10.0.0.241 2026-04-06 03:34:27,295 - INFO - Validated that VN ctest-trans_right_vn-43115504 is not in any agent 2026-04-06 03:34:27,328 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-43115504 info 2026-04-06 03:34:27,328 - INFO - Deleting VN ctest-trans_left_vn-39954421 2026-04-06 03:34:27,581 - DEBUG - Response for deleting network () 2026-04-06 03:34:27,581 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/84a7a96e-af65-48bc-8108-133748bc19fc 2026-04-06 03:34:27,586 - DEBUG - Response Code: 404 2026-04-06 03:34:27,587 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:27,592 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:27,616 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:27,721 - INFO - Validated that VN ctest-trans_left_vn-39954421 is not found in API Server 2026-04-06 03:34:27,734 - DEBUG - VN ctest-trans_left_vn-39954421 is not present in Agent 10.0.0.19 2026-04-06 03:34:27,745 - DEBUG - VN ctest-trans_left_vn-39954421 is not present in Agent 10.0.0.241 2026-04-06 03:34:27,745 - INFO - Validated that VN ctest-trans_left_vn-39954421 is not in any agent 2026-04-06 03:34:27,791 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-39954421 info 2026-04-06 03:34:27,791 - INFO - Deleting VN ctest-mgmt_vn-14166128 2026-04-06 03:34:28,058 - DEBUG - Response for deleting network () 2026-04-06 03:34:28,058 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/be7bd1ce-352a-4a3c-bdea-0b062e95c75c 2026-04-06 03:34:28,065 - DEBUG - Response Code: 404 2026-04-06 03:34:28,065 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:28,071 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:28,081 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:28,181 - INFO - Validated that VN ctest-mgmt_vn-14166128 is not found in API Server 2026-04-06 03:34:28,193 - DEBUG - VN ctest-mgmt_vn-14166128 is not present in Agent 10.0.0.19 2026-04-06 03:34:28,205 - DEBUG - VN ctest-mgmt_vn-14166128 is not present in Agent 10.0.0.241 2026-04-06 03:34:28,205 - INFO - Validated that VN ctest-mgmt_vn-14166128 is not in any agent 2026-04-06 03:34:28,239 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-14166128 info 2026-04-06 03:34:28,240 - INFO - Deleting VN ctest-right-89658150 2026-04-06 03:34:28,397 - DEBUG - Response for deleting network () 2026-04-06 03:34:28,397 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/82ccf826-8167-4ea4-9442-ea5d6ca94a70 2026-04-06 03:34:28,402 - DEBUG - Response Code: 404 2026-04-06 03:34:28,402 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:28,408 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:28,468 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:28,565 - INFO - Validated that VN ctest-right-89658150 is not found in API Server 2026-04-06 03:34:28,576 - DEBUG - VN ctest-right-89658150 is not present in Agent 10.0.0.19 2026-04-06 03:34:28,587 - DEBUG - VN ctest-right-89658150 is not present in Agent 10.0.0.241 2026-04-06 03:34:28,587 - INFO - Validated that VN ctest-right-89658150 is not in any agent 2026-04-06 03:34:28,617 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right-89658150 info 2026-04-06 03:34:28,617 - INFO - Deleting VN ctest-left-56628630 2026-04-06 03:34:28,812 - DEBUG - Response for deleting network () 2026-04-06 03:34:28,812 - DEBUG - Requesting: http://10.0.0.249:8082/routing-instance/5629c4b6-bba1-4fd0-9d8b-5531d89f78c4 2026-04-06 03:34:28,818 - DEBUG - Response Code: 404 2026-04-06 03:34:28,819 - DEBUG - Requesting: http://10.0.0.249:8082/domains 2026-04-06 03:34:28,823 - DEBUG - Requesting: http://10.0.0.249:8082/domain/500738d8-636d-4330-98c4-d5dafb5cbb39 2026-04-06 03:34:28,841 - DEBUG - Requesting: http://10.0.0.249:8082/project/d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4 2026-04-06 03:34:28,922 - INFO - Validated that VN ctest-left-56628630 is not found in API Server 2026-04-06 03:34:28,935 - DEBUG - VN ctest-left-56628630 is not present in Agent 10.0.0.19 2026-04-06 03:34:28,945 - DEBUG - VN ctest-left-56628630 is not present in Agent 10.0.0.241 2026-04-06 03:34:28,945 - INFO - Validated that VN ctest-left-56628630 is not in any agent 2026-04-06 03:34:28,974 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left-56628630 info 2026-04-06 03:34:30,285 - DEBUG - No XMPP flaps were noticed during the test 2026-04-06 03:34:30,285 - INFO -

2026-04-06 03:34:30,285 - INFO - END TEST : test_svc_v2_mirroring : PASSED[0:02:49]

2026-04-06 03:34:30,285 - INFO - -------------------------------------------------------------------------------- 2026-04-06 03:34:31,088 - INFO - Deleted project: ctest-TestSVCV2Mirror-59554623, ID : d9ddd5e8-c1b6-41fe-9f93-a34ae8e75bd4