2025-12-29 03:13:15,032 - INFO - Domain Default found not creating 2025-12-29 03:13:15,214 - INFO - Project ctest-TestSVCV2Mirror-48523992 not found, creating it 2025-12-29 03:13:15,762 - INFO - Created Project:ctest-TestSVCV2Mirror-48523992, ID : 7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:17,266 - INFO - ================================================================================ 2025-12-29 03:13:17,266 - INFO - STARTING TEST : test_svc_v2_mirroring 2025-12-29 03:13:17,267 - INFO - TEST DESCRIPTION : Validate the service chain mirroring 2025-12-29 03:13:18,543 - DEBUG - Nothing to compare xmpp stats {'10.0.0.56': {'10.20.0.145': '0', '10.20.0.18': '0'}, '10.0.0.240': {'10.20.0.252': '0', '10.20.0.18': '0'}} with 2025-12-29 03:13:18,544 - INFO - Initial checks done. Running the testcase now 2025-12-29 03:13:18,544 - INFO - 2025-12-29 03:13:19,180 - DEBUG - Response for create_network : {'network': {'id': '2d01e6e7-ece5-4d9a-924b-517d56ce0fc1', 'name': 'ctest-left-78746301', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'project_id': '7049c078451c43838b8c290c1c407401', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-left-78746301'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 03:13:19,457 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eecc8542-8458-43ea-8c26-f652f6504933', 'name': '', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'network_id': '2d01e6e7-ece5-4d9a-924b-517d56ce0fc1', 'ip_version': 4, 'cidr': '34.80.224.64/26', 'allocation_pools': [{'start': '34.80.224.66', 'end': '34.80.224.126'}], 'gateway_ip': '34.80.224.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '34.80.224.66', 'tags': [], 'project_id': '7049c078451c43838b8c290c1c407401'}} 2025-12-29 03:13:19,478 - INFO - Created VN ctest-left-78746301 2025-12-29 03:13:19,491 - DEBUG - VN ctest-left-78746301 UUID is 2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:19,555 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:19,854 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:19,889 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:19,904 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:19,999 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,015 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,025 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:13:20,032 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:13:20,039 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/f1a65099-20b6-4eca-b1e5-6a78f0fc18a8 2025-12-29 03:13:20,045 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-29 03:13:20,045 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,054 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:13:20,070 - INFO - Verified VN network id 11 for VN 2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,070 - INFO - Verifications in API Server for VN ctest-left-78746301 passed 2025-12-29 03:13:20,071 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,080 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:13:20,088 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:13:20,095 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/f1a65099-20b6-4eca-b1e5-6a78f0fc18a8 2025-12-29 03:13:20,107 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3243127090469752218', 'uuid-lslong': '10541608951559884737'}, 'enable': 'true', 'created': '2025-12-29T03:13:19', 'last-modified': '2025-12-29T03:13:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.705364'} 2025-12-29 03:13:20,112 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-29 03:13:20,117 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3243127090469752218', 'uuid-lslong': '10541608951559884737'}, 'enable': 'true', 'created': '2025-12-29T03:13:19', 'last-modified': '2025-12-29T03:13:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.718488'} 2025-12-29 03:13:20,122 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-29 03:13:20,128 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3243127090469752218', 'uuid-lslong': '10541608951559884737'}, 'enable': 'true', 'created': '2025-12-29T03:13:19', 'last-modified': '2025-12-29T03:13:19', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.724497'} 2025-12-29 03:13:20,134 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-12-29 03:13:20,134 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left-78746301 passed 2025-12-29 03:13:20,134 - DEBUG - ====Verifying policy data for ctest-left-78746301 in API_Server ====== 2025-12-29 03:13:20,134 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:20,140 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:20,150 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:20,243 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/2d01e6e7-ece5-4d9a-924b-517d56ce0fc1 2025-12-29 03:13:20,255 - DEBUG - =>VN ctest-left-78746301 has no policy to be verified 2025-12-29 03:13:20,255 - DEBUG - Verifying the vn in opserver 2025-12-29 03:13:20,255 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301 virtual network link through opserver 10.0.0.61 2025-12-29 03:13:20,255 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:20,555 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:20,615 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301?flat'} 2025-12-29 03:13:20,615 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301 virtual network link through opserver 10.0.0.22 2025-12-29 03:13:20,615 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:20,914 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:20,973 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301?flat'} 2025-12-29 03:13:20,973 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301 virtual network link through opserver 10.0.0.27 2025-12-29 03:13:20,973 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:21,268 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:21,348 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301?flat'} 2025-12-29 03:13:21,348 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301 is found in opserver 2025-12-29 03:13:21,367 - DEBUG - Do not have enough data to verify VN in agent 2025-12-29 03:13:21,376 - DEBUG - VRF ids for VN ctest-left-78746301: {} 2025-12-29 03:13:21,640 - DEBUG - Response for create_network : {'network': {'id': '8479d9a2-a9b2-4adb-86ac-a9c93463397d', 'name': 'ctest-right-80437307', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'project_id': '7049c078451c43838b8c290c1c407401', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-right-80437307'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 03:13:21,962 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f2da34c4-7a05-4288-ae50-c57c57ce1afd', 'name': '', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'network_id': '8479d9a2-a9b2-4adb-86ac-a9c93463397d', 'ip_version': 4, 'cidr': '29.205.99.128/26', 'allocation_pools': [{'start': '29.205.99.130', 'end': '29.205.99.190'}], 'gateway_ip': '29.205.99.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '29.205.99.130', 'tags': [], 'project_id': '7049c078451c43838b8c290c1c407401'}} 2025-12-29 03:13:21,988 - INFO - Created VN ctest-right-80437307 2025-12-29 03:13:22,001 - DEBUG - VN ctest-right-80437307 UUID is 8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,073 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:22,081 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:22,092 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:22,182 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,194 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,204 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:13:22,212 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:13:22,219 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/4ee65213-175d-4505-bd75-cf52777a2d7c 2025-12-29 03:13:22,228 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-29 03:13:22,228 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,237 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:13:22,254 - INFO - Verified VN network id 12 for VN 8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,254 - INFO - Verifications in API Server for VN ctest-right-80437307 passed 2025-12-29 03:13:22,254 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,265 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:13:22,277 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:13:22,283 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/4ee65213-175d-4505-bd75-cf52777a2d7c 2025-12-29 03:13:22,293 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9545900177843440347', 'uuid-lslong': '9704317978709014909'}, 'enable': 'true', 'created': '2025-12-29T03:13:21', 'last-modified': '2025-12-29T03:13:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.418189'} 2025-12-29 03:13:22,298 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-29 03:13:22,303 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9545900177843440347', 'uuid-lslong': '9704317978709014909'}, 'enable': 'true', 'created': '2025-12-29T03:13:21', 'last-modified': '2025-12-29T03:13:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.430635'} 2025-12-29 03:13:22,309 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-29 03:13:22,315 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9545900177843440347', 'uuid-lslong': '9704317978709014909'}, 'enable': 'true', 'created': '2025-12-29T03:13:21', 'last-modified': '2025-12-29T03:13:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.440248'} 2025-12-29 03:13:22,322 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-12-29 03:13:22,322 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right-80437307 passed 2025-12-29 03:13:22,322 - DEBUG - ====Verifying policy data for ctest-right-80437307 in API_Server ====== 2025-12-29 03:13:22,322 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:22,328 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:22,344 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:22,437 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/8479d9a2-a9b2-4adb-86ac-a9c93463397d 2025-12-29 03:13:22,450 - DEBUG - =>VN ctest-right-80437307 has no policy to be verified 2025-12-29 03:13:22,450 - DEBUG - Verifying the vn in opserver 2025-12-29 03:13:22,450 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307 virtual network link through opserver 10.0.0.61 2025-12-29 03:13:22,450 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:22,513 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307?flat'} 2025-12-29 03:13:22,513 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307 virtual network link through opserver 10.0.0.22 2025-12-29 03:13:22,513 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:22,576 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307?flat'} 2025-12-29 03:13:22,576 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307 virtual network link through opserver 10.0.0.27 2025-12-29 03:13:22,577 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:22,634 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307?flat'} 2025-12-29 03:13:22,634 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307 is found in opserver 2025-12-29 03:13:22,657 - DEBUG - Do not have enough data to verify VN in agent 2025-12-29 03:13:22,670 - DEBUG - VRF ids for VN ctest-right-80437307: {} 2025-12-29 03:13:22,932 - DEBUG - Response for create_network : {'network': {'id': '96ac92d6-6641-4e12-931d-d9e59ac5ddcc', 'name': 'ctest-mgmt_vn-26307744', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'project_id': '7049c078451c43838b8c290c1c407401', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-mgmt_vn-26307744'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 03:13:23,149 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e0430cd8-afaa-49e3-82fc-3665f043e3d4', 'name': '', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'network_id': '96ac92d6-6641-4e12-931d-d9e59ac5ddcc', 'ip_version': 4, 'cidr': '89.141.154.64/26', 'allocation_pools': [{'start': '89.141.154.66', 'end': '89.141.154.126'}], 'gateway_ip': '89.141.154.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.141.154.66', 'tags': [], 'project_id': '7049c078451c43838b8c290c1c407401'}} 2025-12-29 03:13:23,175 - INFO - Created VN ctest-mgmt_vn-26307744 2025-12-29 03:13:23,185 - DEBUG - VN ctest-mgmt_vn-26307744 UUID is 96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,256 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:23,263 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:23,274 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:23,373 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,386 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,398 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:13:23,408 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:13:23,415 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/264080f2-3a20-4dfc-a0e5-8157526ba12c 2025-12-29 03:13:23,422 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-29 03:13:23,422 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,431 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:13:23,448 - INFO - Verified VN network id 13 for VN 96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,448 - INFO - Verifications in API Server for VN ctest-mgmt_vn-26307744 passed 2025-12-29 03:13:23,449 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,458 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:13:23,466 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:13:23,474 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/264080f2-3a20-4dfc-a0e5-8157526ba12c 2025-12-29 03:13:23,487 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10857214251219635730', 'uuid-lslong': '10600868678067412428'}, 'enable': 'true', 'created': '2025-12-29T03:13:22', 'last-modified': '2025-12-29T03:13:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.369532'} 2025-12-29 03:13:23,493 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-29 03:13:23,497 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10857214251219635730', 'uuid-lslong': '10600868678067412428'}, 'enable': 'true', 'created': '2025-12-29T03:13:22', 'last-modified': '2025-12-29T03:13:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.386833'} 2025-12-29 03:13:23,503 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-29 03:13:23,509 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10857214251219635730', 'uuid-lslong': '10600868678067412428'}, 'enable': 'true', 'created': '2025-12-29T03:13:22', 'last-modified': '2025-12-29T03:13:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395123'} 2025-12-29 03:13:23,518 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-12-29 03:13:23,518 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-26307744 passed 2025-12-29 03:13:23,518 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-26307744 in API_Server ====== 2025-12-29 03:13:23,518 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:23,525 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:23,537 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:23,634 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/96ac92d6-6641-4e12-931d-d9e59ac5ddcc 2025-12-29 03:13:23,650 - DEBUG - =>VN ctest-mgmt_vn-26307744 has no policy to be verified 2025-12-29 03:13:23,650 - DEBUG - Verifying the vn in opserver 2025-12-29 03:13:23,650 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744 virtual network link through opserver 10.0.0.61 2025-12-29 03:13:23,650 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:23,715 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744?flat'} 2025-12-29 03:13:23,715 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744 virtual network link through opserver 10.0.0.22 2025-12-29 03:13:23,715 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:23,775 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744?flat'} 2025-12-29 03:13:23,775 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744 virtual network link through opserver 10.0.0.27 2025-12-29 03:13:23,775 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:23,849 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744?flat'} 2025-12-29 03:13:23,849 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-mgmt_vn-26307744 is found in opserver 2025-12-29 03:13:23,873 - DEBUG - Do not have enough data to verify VN in agent 2025-12-29 03:13:23,886 - DEBUG - VRF ids for VN ctest-mgmt_vn-26307744: {} 2025-12-29 03:13:24,145 - DEBUG - Response for create_network : {'network': {'id': '4a797636-4180-43b9-a9ed-1eae480fb373', 'name': 'ctest-trans_left_vn-15105203', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'project_id': '7049c078451c43838b8c290c1c407401', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-trans_left_vn-15105203'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 03:13:24,379 - DEBUG - Response for create_subnet : {'subnet': {'id': '6b4ca595-6239-41a3-8e45-18589d173e76', 'name': '', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'network_id': '4a797636-4180-43b9-a9ed-1eae480fb373', 'ip_version': 4, 'cidr': '135.243.52.64/26', 'allocation_pools': [{'start': '135.243.52.66', 'end': '135.243.52.126'}], 'gateway_ip': '135.243.52.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.243.52.66', 'tags': [], 'project_id': '7049c078451c43838b8c290c1c407401'}} 2025-12-29 03:13:24,402 - INFO - Created VN ctest-trans_left_vn-15105203 2025-12-29 03:13:24,412 - DEBUG - VN ctest-trans_left_vn-15105203 UUID is 4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,489 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:24,496 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:24,507 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:24,599 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,619 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,629 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:13:24,636 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:13:24,642 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/449a9d1e-c433-4515-aeb5-f6471f71a703 2025-12-29 03:13:24,649 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-29 03:13:24,649 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,658 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:13:24,673 - INFO - Verified VN network id 14 for VN 4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,674 - INFO - Verifications in API Server for VN ctest-trans_left_vn-15105203 passed 2025-12-29 03:13:24,674 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,683 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:13:24,692 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:13:24,701 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/449a9d1e-c433-4515-aeb5-f6471f71a703 2025-12-29 03:13:24,716 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5366450406387893177', 'uuid-lslong': '12244476695772377971'}, 'enable': 'true', 'created': '2025-12-29T03:13:24', 'last-modified': '2025-12-29T03:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.381930'} 2025-12-29 03:13:24,726 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-29 03:13:24,730 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5366450406387893177', 'uuid-lslong': '12244476695772377971'}, 'enable': 'true', 'created': '2025-12-29T03:13:24', 'last-modified': '2025-12-29T03:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414584'} 2025-12-29 03:13:24,735 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-29 03:13:24,741 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5366450406387893177', 'uuid-lslong': '12244476695772377971'}, 'enable': 'true', 'created': '2025-12-29T03:13:24', 'last-modified': '2025-12-29T03:13:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.426629'} 2025-12-29 03:13:24,747 - DEBUG - Route Targets: ['target:64512:8000008'] 2025-12-29 03:13:24,747 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_left_vn-15105203 passed 2025-12-29 03:13:24,748 - DEBUG - ====Verifying policy data for ctest-trans_left_vn-15105203 in API_Server ====== 2025-12-29 03:13:24,748 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:24,754 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:24,765 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:24,854 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/4a797636-4180-43b9-a9ed-1eae480fb373 2025-12-29 03:13:24,863 - DEBUG - =>VN ctest-trans_left_vn-15105203 has no policy to be verified 2025-12-29 03:13:24,863 - DEBUG - Verifying the vn in opserver 2025-12-29 03:13:24,863 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 virtual network link through opserver 10.0.0.61 2025-12-29 03:13:24,864 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:24,875 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203?flat'} 2025-12-29 03:13:24,875 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 virtual network link through opserver 10.0.0.22 2025-12-29 03:13:24,875 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:24,885 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203?flat'} 2025-12-29 03:13:24,885 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 virtual network link through opserver 10.0.0.27 2025-12-29 03:13:24,885 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:24,897 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203?flat'} 2025-12-29 03:13:24,897 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is found in opserver 2025-12-29 03:13:24,920 - DEBUG - Do not have enough data to verify VN in agent 2025-12-29 03:13:24,929 - DEBUG - VRF ids for VN ctest-trans_left_vn-15105203: {} 2025-12-29 03:13:25,165 - DEBUG - Response for create_network : {'network': {'id': '85faefc2-b054-4caf-8646-b8a614874714', 'name': 'ctest-trans_right_vn-24533615', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'project_id': '7049c078451c43838b8c290c1c407401', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-trans_right_vn-24533615'], 'port_security_enabled': True, 'description': ''}} 2025-12-29 03:13:25,371 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cd958207-f2a6-445c-ab5f-abf0373f7a9e', 'name': '', 'tenant_id': '7049c078451c43838b8c290c1c407401', 'network_id': '85faefc2-b054-4caf-8646-b8a614874714', 'ip_version': 4, 'cidr': '158.46.137.0/26', 'allocation_pools': [{'start': '158.46.137.2', 'end': '158.46.137.62'}], 'gateway_ip': '158.46.137.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '158.46.137.2', 'tags': [], 'project_id': '7049c078451c43838b8c290c1c407401'}} 2025-12-29 03:13:25,392 - INFO - Created VN ctest-trans_right_vn-24533615 2025-12-29 03:13:25,404 - DEBUG - VN ctest-trans_right_vn-24533615 UUID is 85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,471 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:25,477 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:25,487 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:25,582 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,592 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,603 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:13:25,609 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:13:25,615 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/e809b227-ebed-40cd-a8fd-03b16cff40a5 2025-12-29 03:13:25,621 - DEBUG - Route Targets: ['target:64512:8000009'] 2025-12-29 03:13:25,621 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,628 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:13:25,646 - INFO - Verified VN network id 15 for VN 85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,646 - INFO - Verifications in API Server for VN ctest-trans_right_vn-24533615 passed 2025-12-29 03:13:25,646 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,654 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:13:25,661 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:13:25,668 - DEBUG - Requesting: http://10.0.0.61:8082/route-target/e809b227-ebed-40cd-a8fd-03b16cff40a5 2025-12-29 03:13:25,679 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9654292370683088047', 'uuid-lslong': '9675623872900581140'}, 'enable': 'true', 'created': '2025-12-29T03:13:25', 'last-modified': '2025-12-29T03:13:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.373288'} 2025-12-29 03:13:25,685 - DEBUG - Route Targets: ['target:64512:8000009'] 2025-12-29 03:13:25,690 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9654292370683088047', 'uuid-lslong': '9675623872900581140'}, 'enable': 'true', 'created': '2025-12-29T03:13:25', 'last-modified': '2025-12-29T03:13:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.383607'} 2025-12-29 03:13:25,696 - DEBUG - Route Targets: ['target:64512:8000009'] 2025-12-29 03:13:25,701 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSVCV2Mirror-48523992', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9654292370683088047', 'uuid-lslong': '9675623872900581140'}, 'enable': 'true', 'created': '2025-12-29T03:13:25', 'last-modified': '2025-12-29T03:13:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.395065'} 2025-12-29 03:13:25,706 - DEBUG - Route Targets: ['target:64512:8000009'] 2025-12-29 03:13:25,706 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-trans_right_vn-24533615 passed 2025-12-29 03:13:25,706 - DEBUG - ====Verifying policy data for ctest-trans_right_vn-24533615 in API_Server ====== 2025-12-29 03:13:25,707 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:13:25,712 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:13:25,723 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:13:25,839 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-network/85faefc2-b054-4caf-8646-b8a614874714 2025-12-29 03:13:25,848 - DEBUG - =>VN ctest-trans_right_vn-24533615 has no policy to be verified 2025-12-29 03:13:25,848 - DEBUG - Verifying the vn in opserver 2025-12-29 03:13:25,848 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615 virtual network link through opserver 10.0.0.61 2025-12-29 03:13:25,848 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks 2025-12-29 03:13:25,863 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615?flat'} 2025-12-29 03:13:25,863 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615 virtual network link through opserver 10.0.0.22 2025-12-29 03:13:25,863 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-12-29 03:13:25,875 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615?flat'} 2025-12-29 03:13:25,875 - DEBUG - Verifying the default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615 virtual network link through opserver 10.0.0.27 2025-12-29 03:13:25,875 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2025-12-29 03:13:25,889 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615?flat'} 2025-12-29 03:13:25,889 - INFO - Validated that VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_right_vn-24533615 is found in opserver 2025-12-29 03:13:25,913 - DEBUG - Do not have enough data to verify VN in agent 2025-12-29 03:13:25,925 - DEBUG - VRF ids for VN ctest-trans_right_vn-24533615: {} 2025-12-29 03:13:25,996 - DEBUG - Services list from nova: [, , , ] 2025-12-29 03:13:27,016 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 03:13:28,240 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-2) 2025-12-29 03:13:28,240 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-89431598'] 2025-12-29 03:13:28,527 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-89431598'] created succesfully 2025-12-29 03:13:30,022 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4934-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4934-1) 2025-12-29 03:13:30,022 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:13:30,487 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:13:30,531 - DEBUG - Response Code: 404 2025-12-29 03:13:32,531 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:13:32,543 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:13:32,624 - INFO - Waiting for VM ctest-pt_svm0-55886465 to be up.. 2025-12-29 03:13:32,734 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-29 03:13:37,834 - DEBUG - VM is in ACTIVE state now 2025-12-29 03:13:37,834 - INFO - VM name : ctest-pt_svm0-55886465 2025-12-29 03:13:37,914 - DEBUG - VM ctest-pt_svm0-55886465 ID is 49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:13:37,937 - DEBUG - VM ctest-pt_svm0-55886465 launched on Node an-jenkins-deploy-platform-ansible-os-4934-1 2025-12-29 03:13:38,022 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:13:38,031 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:13:41,234 - 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 1032ms') 2025-12-29 03:13:41,234 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:13:41,247 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:13:45,330 - 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-29 03:13:45,330 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:13:45,346 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:13:49,421 - 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-29 03:13:49,421 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:13:49,434 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:13:53,522 - 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-29 03:13:53,522 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:13:53,537 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:13:57,617 - 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-29 03:13:57,617 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:13:57,629 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:01,714 - 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-29 03:14:01,714 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:01,728 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:05,809 - 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-29 03:14:05,809 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:05,823 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:09,907 - 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-29 03:14:09,907 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:09,921 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:14,004 - 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 1016ms') 2025-12-29 03:14:14,005 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:14,017 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:18,099 - 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 1006ms') 2025-12-29 03:14:18,099 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:18,113 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:22,195 - 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-29 03:14:22,195 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 failed! 2025-12-29 03:14:22,209 - DEBUG - Gateway for vn default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is 135.243.52.65 and allocation pool is NOT set 2025-12-29 03:14:24,279 - 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=5.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=64 time=5.28 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 = 5.277/5.618/5.960/0.341 ms') 2025-12-29 03:14:24,279 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 passed 2025-12-29 03:14:24,350 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:24,350 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:24,419 - 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-29 03:14:24,519 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:29,520 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:29,520 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:29,587 - 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-29 03:14:29,664 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:34,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:34,664 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:34,731 - 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-29 03:14:34,834 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:39,835 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:39,835 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:39,904 - 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-29 03:14:39,994 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:44,994 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:44,994 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:45,063 - 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-29 03:14:45,145 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:50,146 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:50,146 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:50,211 - 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-29 03:14:50,291 - DEBUG - VM ctest-pt_svm0-55886465 is NOT ready for SSH connections, VM status: ACTIVE 2025-12-29 03:14:55,292 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:55,292 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-55886465, IP 135.243.52.67, Port 22 2025-12-29 03:14:55,463 - DEBUG - VM ctest-pt_svm0-55886465 is ready for SSH connections 2025-12-29 03:14:55,469 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-si-91346850'] 2025-12-29 03:14:55,940 - INFO - Waiting for VM ctest-left_vm-10475576 to be up.. 2025-12-29 03:14:56,041 - DEBUG - VM is in ACTIVE state now 2025-12-29 03:14:56,042 - INFO - VM name : ctest-left_vm-10475576 2025-12-29 03:14:56,185 - DEBUG - VM ctest-left_vm-10475576 ID is 27eebd84-9409-4fc2-91e1-1932ffc95419 2025-12-29 03:14:56,186 - DEBUG - VM ctest-left_vm-10475576 launched on Node an-jenkins-deploy-platform-ansible-os-4934-1 2025-12-29 03:14:56,293 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/27eebd84-9409-4fc2-91e1-1932ffc95419 2025-12-29 03:14:56,303 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a364a511-f135-4574-96e2-1b8c8e42bb5b 2025-12-29 03:14:57,456 - 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.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.869 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.869/2.357/3.846/1.488 ms') 2025-12-29 03:14:57,456 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-10475576 passed 2025-12-29 03:14:57,524 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:57,524 - DEBUG - Waiting to SSH to VM ctest-left_vm-10475576, IP 34.80.224.67, Port 22 2025-12-29 03:14:57,699 - DEBUG - VM ctest-left_vm-10475576 is ready for SSH connections 2025-12-29 03:14:57,699 - INFO - Waiting for VM ctest-right_vm-36735802 to be up.. 2025-12-29 03:14:57,789 - DEBUG - VM is in ACTIVE state now 2025-12-29 03:14:57,789 - INFO - VM name : ctest-right_vm-36735802 2025-12-29 03:14:57,871 - DEBUG - VM ctest-right_vm-36735802 ID is 2fd9b52a-9c28-4e0e-bf42-26587dd380e8 2025-12-29 03:14:57,871 - DEBUG - VM ctest-right_vm-36735802 launched on Node an-jenkins-deploy-platform-ansible-os-4934-2 2025-12-29 03:14:57,955 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2fd9b52a-9c28-4e0e-bf42-26587dd380e8 2025-12-29 03:14:57,964 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/2b0f3e4d-7d49-4ce4-943b-85a9163ce1e4 2025-12-29 03:14:59,115 - 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=5.62 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.398 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.398/3.009/5.620/2.611 ms') 2025-12-29 03:14:59,115 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-right_vm-36735802 passed 2025-12-29 03:14:59,182 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:14:59,182 - DEBUG - Waiting to SSH to VM ctest-right_vm-36735802, IP 29.205.99.131, Port 22 2025-12-29 03:14:59,352 - DEBUG - VM ctest-right_vm-36735802 is ready for SSH connections 2025-12-29 03:14:59,357 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301, 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-48523992:ctest-right-80437307, 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-48523992:ctest-si-91346850, 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-29 03:14:59,680 - INFO - Attaching policy ctest-policy-85038265 to vn ctest-left-78746301 2025-12-29 03:15:00,055 - INFO - Attaching policy ctest-policy-85038265 to vn ctest-right-80437307 2025-12-29 03:15:00,371 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-89431598'] created succesfully 2025-12-29 03:15:00,577 - DEBUG - Service instance: ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-si-91346850'] created succesfully 2025-12-29 03:15:00,577 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:15:00,583 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:15:00,596 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:15:00,693 - DEBUG - Requesting: http://10.0.0.61:8082/service-instance/c5f50475-b3c4-4813-9998-e768d69bbb64 2025-12-29 03:15:00,702 - DEBUG - SI 'ctest-si-91346850' has service template ref to ctest-service_template_1-89431598 2025-12-29 03:15:00,702 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:15:00,708 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:15:00,719 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:15:00,822 - DEBUG - Requesting: http://10.0.0.61:8082/service-instance/c5f50475-b3c4-4813-9998-e768d69bbb64 2025-12-29 03:15:00,835 - DEBUG - Requesting: http://10.0.0.61:8082/port-tuple/cb03cceb-a0ec-4641-8943-871514d6a7c8 2025-12-29 03:15:00,845 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:15:01,048 - DEBUG - VM is in ACTIVE state now 2025-12-29 03:15:01,314 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:15:01,323 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:15:01,338 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/237700a4-5097-48cc-a868-a9aa0465caa0 2025-12-29 03:15:01,349 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/c1dd6c54-ff01-4c09-9844-62af945e07b6 2025-12-29 03:15:01,361 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/e67a165f-a563-4ef4-82b2-b880942b604d 2025-12-29 03:15:01,557 - DEBUG - VM is in ACTIVE state now 2025-12-29 03:15:01,557 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:15:01,566 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:15:02,722 - 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.81 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=64 time=1.39 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.386/3.095/4.805/1.709 ms') 2025-12-29 03:15:02,722 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-pt_svm0-55886465 passed 2025-12-29 03:15:02,765 - DEBUG - VM ctest-pt_svm0-55886465 Tap interface: {'index': '4', 'name': 'tapcdbaffaa-42', 'uuid': 'cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203:ctest-trans_left_vn-15105203', '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': '14', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'vm_uuid': '49f36750-3632-48f0-b819-a9b7e23b0929', 'vm_name': 'ctest-pt_svm0-55886465', 'ip_addr': '135.243.52.67', 'mac_addr': '02:cd:ba:ff:aa:42', '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-48523992:cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff', 'sg_uuid_list': ['34b554b1-0a98-417f-ad74-042f9e8ca59f'], 'static_route_list': None, 'vm_project_uuid': '7049c078-451c-4383-8b8c-290c1c407401', '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-29 03:15:02,765 - DEBUG - Agent 10.0.0.56 vrf name: default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203:ctest-trans_left_vn-15105203 2025-12-29 03:15:02,775 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203:ctest-trans_left_vn-15105203', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.241:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-12-29 03:15:02,864 - DEBUG - VM ctest-pt_svm0-55886465 : Tap interface tapcdbaffaa-42 is set to Active 2025-12-29 03:15:02,865 - DEBUG - Tap interface tapcdbaffaa-42 detail : {'index': '4', 'name': 'tapcdbaffaa-42', 'uuid': 'cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff', 'vrf_name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203:ctest-trans_left_vn-15105203', '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': '14', 'vn_name': 'default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203', 'vm_uuid': '49f36750-3632-48f0-b819-a9b7e23b0929', 'vm_name': 'ctest-pt_svm0-55886465', 'ip_addr': '135.243.52.67', 'mac_addr': '02:cd:ba:ff:aa:42', '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-48523992:cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff', 'sg_uuid_list': ['34b554b1-0a98-417f-ad74-042f9e8ca59f'], 'static_route_list': None, 'vm_project_uuid': '7049c078-451c-4383-8b8c-290c1c407401', '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-29 03:15:02,977 - DEBUG - Starting Layer 2 verification in Agent 2025-12-29 03:15:02,989 - DEBUG - Layer 2 path is seen for VM MAC 02:cd:ba:ff:aa:42 in agent 10.0.0.56 2025-12-29 03:15:02,989 - DEBUG - Active layer 2 route in agent is present for VMI tapcdbaffaa-42 2025-12-29 03:15:02,989 - DEBUG - L2 label(35) matches bw route table and interface table 2025-12-29 03:15:02,997 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is found in Agent of node 10.0.0.56 2025-12-29 03:15:03,130 - DEBUG - VRF IDs of VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 is consistent in agent 10.0.0.56 2025-12-29 03:15:03,131 - DEBUG - Route for VM IP ['135.243.52.67'] is consistent in agent 10.0.0.56 2025-12-29 03:15:03,131 - DEBUG - VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-trans_left_vn-15105203 verification for VM ctest-pt_svm0-55886465 in Agent 10.0.0.56 passed 2025-12-29 03:15:03,245 - DEBUG - Starting all layer 2 verification in agent 10.0.0.56 2025-12-29 03:15:03,256 - DEBUG - Route for VM MAC 02:cd:ba:ff:aa:42 is consistent in agent 10.0.0.56 2025-12-29 03:15:03,263 - INFO - VM ctest-pt_svm0-55886465 verifications in Compute nodes passed 2025-12-29 03:15:03,264 - DEBUG - The SVMs in the SI are : [] 2025-12-29 03:15:03,264 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:15:03,275 - DEBUG - Service VM for SI 'ctest-si-91346850' is launched 2025-12-29 03:15:03,275 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:15:03,281 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:15:03,292 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:15:03,390 - DEBUG - Requesting: http://10.0.0.61:8082/service-instance/c5f50475-b3c4-4813-9998-e768d69bbb64 2025-12-29 03:15:03,404 - DEBUG - SI ctest-si-91346850 has Port Tuple: ['ctest-port_tuple-89784217'] 2025-12-29 03:15:03,468 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301 2025-12-29 03:15:03,572 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSVCV2Mirror-48523992:ctest-right-80437307 2025-12-29 03:15:03,573 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:15:03,573 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:15:03,573 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 29.205.99.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.56, gateway password: c0ntrail123 2025-12-29 03:15:03,573 - DEBUG - ping -s 56 -c 3 -W 1 29.205.99.131 2025-12-29 03:15:51,988 - DEBUG - PING 29.205.99.131 (29.205.99.131) 56(84) bytes of data. 64 bytes from 29.205.99.131: icmp_req=1 ttl=63 time=8.97 ms 64 bytes from 29.205.99.131: icmp_req=2 ttl=63 time=1.44 ms 64 bytes from 29.205.99.131: icmp_req=3 ttl=63 time=1.37 ms --- 29.205.99.131 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.373/3.931/8.976/3.567 ms 2025-12-29 03:15:51,988 - INFO - Ping to IP 29.205.99.131 from VM ctest-left_vm-10475576 passed 2025-12-29 03:15:51,995 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSVCV2Mirror-48523992:ctest-left-78746301, 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-48523992:ctest-right-80437307, 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-48523992:ctest-si-91346850, 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-48523992:ctest-left-78746301, 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-48523992:ctest-right-80437307, 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-48523992:ctest-si-91346850, 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-29 03:15:52,064 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-29 03:15:52,065 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:15:52,070 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:15:52,082 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:15:52,199 - DEBUG - Requesting: http://10.0.0.61:8082/service-instance/c5f50475-b3c4-4813-9998-e768d69bbb64 2025-12-29 03:15:52,209 - DEBUG - Requesting: http://10.0.0.61:8082/port-tuple/cb03cceb-a0ec-4641-8943-871514d6a7c8 2025-12-29 03:15:52,217 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:15:52,455 - DEBUG - Executing command: rm -f /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap 2025-12-29 03:16:02,574 - INFO - Starting tcpdump to capture the mirrored packets. 2025-12-29 03:16:02,574 - DEBUG - Executing command: sudo tcpdump -ni tapcdbaffaa-42 -U udp port 8099 -w /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap 2025-12-29 03:16:12,610 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 29.205.99.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.56, gateway password: c0ntrail123 2025-12-29 03:16:12,611 - DEBUG - ping -s 56 -c 3 -W 1 29.205.99.131 2025-12-29 03:16:15,159 - DEBUG - PING 29.205.99.131 (29.205.99.131) 56(84) bytes of data. 64 bytes from 29.205.99.131: icmp_req=1 ttl=63 time=2.88 ms 64 bytes from 29.205.99.131: icmp_req=2 ttl=63 time=1.43 ms 64 bytes from 29.205.99.131: icmp_req=3 ttl=63 time=1.29 ms --- 29.205.99.131 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.290/1.869/2.883/0.719 ms 2025-12-29 03:16:15,159 - INFO - Ping to IP 29.205.99.131 from VM ctest-left_vm-10475576 passed 2025-12-29 03:16:15,159 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:15,167 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:15,179 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:15,292 - DEBUG - Requesting: http://10.0.0.61:8082/service-instance/c5f50475-b3c4-4813-9998-e768d69bbb64 2025-12-29 03:16:15,302 - DEBUG - Requesting: http://10.0.0.61:8082/port-tuple/cb03cceb-a0ec-4641-8943-871514d6a7c8 2025-12-29 03:16:15,309 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/cdbaffaa-42a4-4ea9-9d0e-1a77a0d7d0ff 2025-12-29 03:16:15,438 - DEBUG - Waiting for the tcpdump write to complete. 2025-12-29 03:16:17,439 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap| awk '{print $2}') 2025-12-29 03:16:17,442 - DEBUG - Executing command: sync 2025-12-29 03:16:20,467 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap | wc -l 2025-12-29 03:16:20,484 - DEBUG - STDOUT: 12 2025-12-29 03:16:20,484 - DEBUG - STDERR: reading from file /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-12-29 03:16:20,484 - DEBUG - Executing command: sudo tcpdump -n -r /tmp/mirror-tapcdbaffaa-42_ctest-random-71013813.pcap 2025-12-29 03:16:20,526 - INFO - 12 ICMP packets are mirrored to the analyzer service VM 'ctest-pt_svm0-55886465' 2025-12-29 03:16:20,526 - INFO - Dettaching policy ctest-policy-85038265 from vn ctest-right-80437307 2025-12-29 03:16:20,663 - INFO - Dettaching policy ctest-policy-85038265 from vn ctest-left-78746301 2025-12-29 03:16:20,888 - INFO - Deleted policy ctest-policy-85038265 2025-12-29 03:16:20,889 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-si-91346850'] 2025-12-29 03:16:20,985 - INFO - Deleted SI ['default-domain', 'ctest-TestSVCV2Mirror-48523992', 'ctest-si-91346850'] 2025-12-29 03:16:20,986 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:20,999 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:21,013 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:21,115 - DEBUG - Service instance ctest-si-91346850 removed from api server 2025-12-29 03:16:21,115 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/49f36750-3632-48f0-b819-a9b7e23b0929 2025-12-29 03:16:21,124 - DEBUG - All Service VMs unlinked from SI ctest-si-91346850 2025-12-29 03:16:21,129 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-29 03:16:21,129 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-29 03:16:21,176 - DEBUG - 0 svc intances found in all projects. They are [] 2025-12-29 03:16:21,176 - DEBUG - 0 svc intances found in current project. They are [] 2025-12-29 03:16:21,176 - INFO - Deleting VM ctest-pt_svm0-55886465 2025-12-29 03:16:21,388 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-89431598'] 2025-12-29 03:16:21,489 - INFO - Service template: ['default-domain', 'ctest-service_template_1-89431598'] deleted successfully. 2025-12-29 03:16:21,489 - INFO - Deleting VM ctest-right_vm-36735802 2025-12-29 03:16:21,627 - INFO - Deleting VM ctest-left_vm-10475576 2025-12-29 03:16:21,804 - INFO - Deleting VN ctest-trans_right_vn-24533615 2025-12-29 03:16:21,942 - DEBUG - Response for deleting network () 2025-12-29 03:16:21,942 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/91329d39-d0aa-423f-8b42-37a7a0b1b8c5 2025-12-29 03:16:21,949 - DEBUG - Response Code: 404 2025-12-29 03:16:21,949 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:21,960 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:21,988 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:22,091 - INFO - Validated that VN ctest-trans_right_vn-24533615 is not found in API Server 2025-12-29 03:16:22,109 - DEBUG - VN ctest-trans_right_vn-24533615 is not present in Agent 10.0.0.56 2025-12-29 03:16:22,125 - DEBUG - VN ctest-trans_right_vn-24533615 is not present in Agent 10.0.0.240 2025-12-29 03:16:22,126 - INFO - Validated that VN ctest-trans_right_vn-24533615 is not in any agent 2025-12-29 03:16:22,171 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_right_vn-24533615 info 2025-12-29 03:16:22,171 - INFO - Deleting VN ctest-trans_left_vn-15105203 2025-12-29 03:16:22,356 - DEBUG - Response for deleting network () 2025-12-29 03:16:22,356 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/ef8a028e-d182-4271-a306-085e6faa4b11 2025-12-29 03:16:22,362 - DEBUG - Response Code: 404 2025-12-29 03:16:22,362 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:22,368 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:22,410 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:22,511 - INFO - Validated that VN ctest-trans_left_vn-15105203 is not found in API Server 2025-12-29 03:16:22,523 - DEBUG - VN ctest-trans_left_vn-15105203 is not present in Agent 10.0.0.56 2025-12-29 03:16:22,533 - DEBUG - VN ctest-trans_left_vn-15105203 is not present in Agent 10.0.0.240 2025-12-29 03:16:22,533 - INFO - Validated that VN ctest-trans_left_vn-15105203 is not in any agent 2025-12-29 03:16:22,584 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-trans_left_vn-15105203 info 2025-12-29 03:16:22,585 - INFO - Deleting VN ctest-mgmt_vn-26307744 2025-12-29 03:16:22,791 - DEBUG - Response for deleting network () 2025-12-29 03:16:22,792 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/4ca88494-df56-45f3-9493-12d004d48779 2025-12-29 03:16:22,799 - DEBUG - Response Code: 404 2025-12-29 03:16:22,799 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:22,809 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:22,827 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:22,968 - INFO - Validated that VN ctest-mgmt_vn-26307744 is not found in API Server 2025-12-29 03:16:22,990 - DEBUG - VN ctest-mgmt_vn-26307744 is not present in Agent 10.0.0.56 2025-12-29 03:16:23,003 - DEBUG - VN ctest-mgmt_vn-26307744 is not present in Agent 10.0.0.240 2025-12-29 03:16:23,003 - INFO - Validated that VN ctest-mgmt_vn-26307744 is not in any agent 2025-12-29 03:16:23,054 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-26307744 info 2025-12-29 03:16:23,054 - INFO - Deleting VN ctest-right-80437307 2025-12-29 03:16:23,223 - DEBUG - Response for deleting network () 2025-12-29 03:16:23,223 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/89cd8160-23b8-4ab6-bd12-cc9c08f6d947 2025-12-29 03:16:23,229 - DEBUG - Response Code: 404 2025-12-29 03:16:23,229 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:23,244 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:23,290 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:23,390 - INFO - Validated that VN ctest-right-80437307 is not found in API Server 2025-12-29 03:16:23,402 - DEBUG - VN ctest-right-80437307 is not present in Agent 10.0.0.56 2025-12-29 03:16:23,413 - DEBUG - VN ctest-right-80437307 is not present in Agent 10.0.0.240 2025-12-29 03:16:23,413 - INFO - Validated that VN ctest-right-80437307 is not in any agent 2025-12-29 03:16:23,443 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right-80437307 info 2025-12-29 03:16:23,443 - INFO - Deleting VN ctest-left-78746301 2025-12-29 03:16:23,645 - DEBUG - Response for deleting network () 2025-12-29 03:16:23,646 - DEBUG - Requesting: http://10.0.0.61:8082/routing-instance/eb9dcb03-2481-43b6-86bb-b67e6b5e79a9 2025-12-29 03:16:23,651 - DEBUG - Response Code: 404 2025-12-29 03:16:23,651 - DEBUG - Requesting: http://10.0.0.61:8082/domains 2025-12-29 03:16:23,657 - DEBUG - Requesting: http://10.0.0.61:8082/domain/ea4b09a7-31d2-48cf-9e60-035b93d3d81c 2025-12-29 03:16:23,669 - DEBUG - Requesting: http://10.0.0.61:8082/project/7049c078-451c-4383-8b8c-290c1c407401 2025-12-29 03:16:23,759 - INFO - Validated that VN ctest-left-78746301 is not found in API Server 2025-12-29 03:16:23,771 - DEBUG - VN ctest-left-78746301 is not present in Agent 10.0.0.56 2025-12-29 03:16:23,782 - DEBUG - VN ctest-left-78746301 is not present in Agent 10.0.0.240 2025-12-29 03:16:23,782 - INFO - Validated that VN ctest-left-78746301 is not in any agent 2025-12-29 03:16:23,814 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left-78746301 info 2025-12-29 03:16:25,105 - DEBUG - No XMPP flaps were noticed during the test 2025-12-29 03:16:25,105 - INFO -

2025-12-29 03:16:25,105 - INFO - END TEST : test_svc_v2_mirroring : PASSED[0:03:08]

2025-12-29 03:16:25,105 - INFO - -------------------------------------------------------------------------------- 2025-12-29 03:16:25,847 - INFO - Deleted project: ctest-TestSVCV2Mirror-48523992, ID : 7049c078-451c-4383-8b8c-290c1c407401