2025-09-04 03:21:16,158 - INFO - Domain Default found not creating 2025-09-04 03:21:16,300 - INFO - Project ctest-DisablePolicyEcmpIpv6-73803047 not found, creating it 2025-09-04 03:21:16,841 - INFO - Created Project:ctest-DisablePolicyEcmpIpv6-73803047, ID : fdd5a1a3-d30e-4260-869e-4359849e9a07 2025-09-04 03:21:18,704 - INFO - ================================================================================ 2025-09-04 03:21:18,704 - INFO - STARTING TEST : test_ecmp_with_static_routes 2025-09-04 03:21:18,704 - INFO - TEST DESCRIPTION : Description: Verify disabling policy for ECMP routes with static routes on VM Steps: 1. launch 1 VN and launch 3 VMs in it. 2. create a static route for a new subnet prefix and add this on 2 VMIs. this will create 2 ECMP routes. 3. Disable the policy on all VMIs. 4. Now from 3rd VM send traffic to an IP from static route prefix 5. add new ECMP destinations and verify load is distributed to new destinations too 6. remove ECMP destinations and verify load is distributed to remaining destinations Pass criteria: 1. traffic should go through fine 2. flows should not be created 3. load should be distributed among ecmp routes. 2025-09-04 03:21:20,009 - DEBUG - Nothing to compare xmpp stats {'10.0.0.241': {'10.20.0.25': '0', '10.20.0.254': '0'}, '10.0.0.242': {'10.20.0.25': '0', '10.20.0.254': '0'}} with 2025-09-04 03:21:20,009 - INFO - Address family configured is dual 2025-09-04 03:21:20,009 - INFO - we are setting address family as v6 2025-09-04 03:21:20,009 - INFO - we are setting address family as v6 2025-09-04 03:21:20,009 - INFO - Initial checks done. Running the testcase now 2025-09-04 03:21:20,009 - INFO - 2025-09-04 03:21:20,048 - DEBUG - Services list from nova: [, , , ] 2025-09-04 03:21:20,048 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4012-1', 'an-jenkins-deploy-platform-ansible-os-4012-2']} 2025-09-04 03:21:20,717 - DEBUG - Response for create_network : {'network': {'id': '046304bf-09b0-4d89-acfd-3b374e859972', 'name': 'ctest-vn-09307698', 'tenant_id': 'fdd5a1a3d30e4260869e4359849e9a07', 'project_id': 'fdd5a1a3d30e4260869e4359849e9a07', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-DisablePolicyEcmpIpv6-73803047', 'ctest-vn-09307698'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 03:21:21,052 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e94e6d5-a3f5-4bf0-9f6d-4ee06cbf5d20', 'name': '', 'tenant_id': 'fdd5a1a3d30e4260869e4359849e9a07', 'network_id': '046304bf-09b0-4d89-acfd-3b374e859972', 'ip_version': 6, 'cidr': '3634:9c:a5c6:2398:bdeb:5739::/96', 'allocation_pools': [{'start': '3634:9c:a5c6:2398:bdeb:5739:0:2', 'end': '3634:9c:a5c6:2398:bdeb:5739:ffff:fffe'}], 'gateway_ip': '3634:9c:a5c6:2398:bdeb:5739:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3634:9c:a5c6:2398:bdeb:5739:0:2', 'tags': [], 'project_id': 'fdd5a1a3d30e4260869e4359849e9a07'}} 2025-09-04 03:21:21,250 - DEBUG - Response for create_subnet : {'subnet': {'id': 'acd39e6d-79fc-47d1-b44c-f47efa6b6181', 'name': '', 'tenant_id': 'fdd5a1a3d30e4260869e4359849e9a07', 'network_id': '046304bf-09b0-4d89-acfd-3b374e859972', 'ip_version': 4, 'cidr': '136.62.36.192/26', 'allocation_pools': [{'start': '136.62.36.194', 'end': '136.62.36.254'}], 'gateway_ip': '136.62.36.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '136.62.36.194', 'tags': [], 'project_id': 'fdd5a1a3d30e4260869e4359849e9a07'}} 2025-09-04 03:21:21,276 - INFO - Created VN ctest-vn-09307698 2025-09-04 03:21:21,287 - DEBUG - VN ctest-vn-09307698 UUID is 046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,386 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 03:21:21,680 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 03:21:21,708 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 03:21:21,722 - DEBUG - Requesting: http://10.0.0.57:8082/project/fdd5a1a3-d30e-4260-869e-4359849e9a07 2025-09-04 03:21:21,811 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,824 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,835 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:21:21,842 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:21:21,850 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/3b1f0e20-16a0-4937-a8a7-4936a7ccfe2d 2025-09-04 03:21:21,857 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 03:21:21,857 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,867 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:21:21,883 - INFO - Verified VN network id 13 for VN 046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,883 - INFO - Verifications in API Server for VN ctest-vn-09307698 passed 2025-09-04 03:21:21,883 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:21,893 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:21:21,901 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:21:21,909 - DEBUG - Requesting: http://10.0.0.57:8082/route-target/3b1f0e20-16a0-4937-a8a7-4936a7ccfe2d 2025-09-04 03:21:21,922 - DEBUG - Control-node 10.0.0.57 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-DisablePolicyEcmpIpv6-73803047', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '316101617393880457', 'uuid-lslong': '12465184452358019442'}, 'enable': 'true', 'created': '2025-09-04T03:21:20', 'last-modified': '2025-09-04T03:21:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.739635'} 2025-09-04 03:21:21,929 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 03:21:21,934 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-DisablePolicyEcmpIpv6-73803047', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '316101617393880457', 'uuid-lslong': '12465184452358019442'}, 'enable': 'true', 'created': '2025-09-04T03:21:20', 'last-modified': '2025-09-04T03:21:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.741737'} 2025-09-04 03:21:21,939 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 03:21:21,945 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-DisablePolicyEcmpIpv6-73803047', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '316101617393880457', 'uuid-lslong': '12465184452358019442'}, 'enable': 'true', 'created': '2025-09-04T03:21:20', 'last-modified': '2025-09-04T03:21:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.760235'} 2025-09-04 03:21:21,950 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-09-04 03:21:21,950 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-09307698 passed 2025-09-04 03:21:21,950 - DEBUG - ====Verifying policy data for ctest-vn-09307698 in API_Server ====== 2025-09-04 03:21:21,950 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 03:21:21,956 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 03:21:21,968 - DEBUG - Requesting: http://10.0.0.57:8082/project/fdd5a1a3-d30e-4260-869e-4359849e9a07 2025-09-04 03:21:22,060 - DEBUG - Requesting: http://10.0.0.57:8082/virtual-network/046304bf-09b0-4d89-acfd-3b374e859972 2025-09-04 03:21:22,070 - DEBUG - =>VN ctest-vn-09307698 has no policy to be verified 2025-09-04 03:21:22,070 - DEBUG - Verifying the vn in opserver 2025-09-04 03:21:22,070 - DEBUG - Verifying the default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 virtual network link through opserver 10.0.0.57 2025-09-04 03:21:22,070 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 03:21:22,366 - DEBUG - Requesting: http://10.0.0.57:8081/analytics/uves/virtual-networks 2025-09-04 03:21:22,424 - DEBUG - vn link and name as {'name': 'default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'href': 'http://10.0.0.57:8081/analytics/uves/virtual-network/default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698?flat'} 2025-09-04 03:21:22,424 - DEBUG - Verifying the default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 virtual network link through opserver 10.0.0.38 2025-09-04 03:21:22,424 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 03:21:22,718 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-09-04 03:21:22,777 - DEBUG - vn link and name as {'name': 'default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698?flat'} 2025-09-04 03:21:22,777 - DEBUG - Verifying the default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 virtual network link through opserver 10.0.0.131 2025-09-04 03:21:22,777 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 03:21:23,070 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2025-09-04 03:21:23,138 - DEBUG - vn link and name as {'name': 'default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698?flat'} 2025-09-04 03:21:23,138 - INFO - Validated that VN default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is found in opserver 2025-09-04 03:21:23,161 - DEBUG - Do not have enough data to verify VN in agent 2025-09-04 03:21:23,173 - DEBUG - VRF ids for VN ctest-vn-09307698: {} 2025-09-04 03:21:23,173 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4012-1', 'an-jenkins-deploy-platform-ansible-os-4012-2']} 2025-09-04 03:21:24,206 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 03:21:24,206 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4012-1', 'an-jenkins-deploy-platform-ansible-os-4012-2']} 2025-09-04 03:21:25,519 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 03:21:27,287 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 03:21:27,287 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4012-1', 'an-jenkins-deploy-platform-ansible-os-4012-2']} 2025-09-04 03:21:28,725 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 03:21:28,725 - INFO - Waiting for VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 to be up.. 2025-09-04 03:21:28,818 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-04 03:21:33,905 - DEBUG - VM is in ACTIVE state now 2025-09-04 03:21:33,905 - INFO - VM name : ctest-DisablePolicyEcmpIpv6-73803047-04443537 2025-09-04 03:21:33,993 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 ID is 24cb89da-3b41-48b6-bd6d-fcb5a8afdb81 2025-09-04 03:21:33,993 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 launched on Node an-jenkins-deploy-platform-ansible-os-4012-2 2025-09-04 03:21:34,075 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/24cb89da-3b41-48b6-bd6d-fcb5a8afdb81 2025-09-04 03:21:34,377 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/24cb89da-3b41-48b6-bd6d-fcb5a8afdb81 2025-09-04 03:21:34,406 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/291313d8-6da9-4799-9483-30239775ffb4 2025-09-04 03:21:37,589 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-04 03:21:37,589 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:37,605 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:37,605 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:21:41,687 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-09-04 03:21:41,688 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:41,702 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:41,702 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:21:45,779 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2025-09-04 03:21:45,779 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:45,792 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:45,792 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:21:49,875 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2025-09-04 03:21:49,876 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:49,891 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:49,891 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:21:53,976 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms') 2025-09-04 03:21:53,977 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:53,995 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:53,995 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:21:58,075 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-09-04 03:21:58,075 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:21:58,092 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:21:58,092 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:02,170 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-04 03:22:02,171 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:02,188 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:02,188 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:06,267 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-09-04 03:22:06,267 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:06,280 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:06,281 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:10,365 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2025-09-04 03:22:10,365 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:10,380 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:10,380 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:14,459 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-09-04 03:22:14,459 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:14,473 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:14,473 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:18,555 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-04 03:22:18,556 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:18,574 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:18,574 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:22,652 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms') 2025-09-04 03:22:22,652 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:22,666 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:22,666 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:26,746 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2025-09-04 03:22:26,747 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 failed! 2025-09-04 03:22:26,764 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 3634:9c:a5c6:2398:bdeb:5739:0:1 and allocation pool is NOT set 2025-09-04 03:22:26,764 - DEBUG - Gateway for vn default-domain:ctest-DisablePolicyEcmpIpv6-73803047:ctest-vn-09307698 is 136.62.36.193 and allocation pool is NOT set 2025-09-04 03:22:28,834 - 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=7.83 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.74 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 = 5.739/6.786/7.833/1.047 ms') 2025-09-04 03:22:28,835 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 passed 2025-09-04 03:22:28,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:28,905 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:28,965 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-09-04 03:22:29,071 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 03:22:34,072 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:34,072 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:34,145 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-09-04 03:22:34,235 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 03:22:39,236 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:39,236 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:39,303 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-09-04 03:22:39,407 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 03:22:44,408 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:44,408 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:44,480 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-09-04 03:22:44,568 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 03:22:49,569 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:49,569 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:49,640 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-09-04 03:22:49,728 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 03:22:54,729 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:54,729 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-04443537, IP 3634:9c:a5c6:2398:bdeb:5739:0:4, Port 22 2025-09-04 03:22:54,899 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 is ready for SSH connections 2025-09-04 03:22:54,900 - INFO - Waiting for VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 to be up.. 2025-09-04 03:22:55,003 - DEBUG - VM is in ACTIVE state now 2025-09-04 03:22:55,003 - INFO - VM name : ctest-DisablePolicyEcmpIpv6-73803047-85740808 2025-09-04 03:22:55,114 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 ID is 998e5323-356c-4efc-950f-aac84673b7ba 2025-09-04 03:22:55,114 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 launched on Node an-jenkins-deploy-platform-ansible-os-4012-2 2025-09-04 03:22:55,238 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/998e5323-356c-4efc-950f-aac84673b7ba 2025-09-04 03:22:55,249 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/2e507958-eb83-449b-8eeb-7371585c2c51 2025-09-04 03:22:56,435 - 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.60 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.71 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.713/3.156/3.600/0.443 ms') 2025-09-04 03:22:56,435 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 passed 2025-09-04 03:22:56,505 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:56,505 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-85740808, IP 3634:9c:a5c6:2398:bdeb:5739:0:5, Port 22 2025-09-04 03:22:56,677 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 is ready for SSH connections 2025-09-04 03:22:56,677 - INFO - Waiting for VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 to be up.. 2025-09-04 03:22:56,757 - DEBUG - VM is in ACTIVE state now 2025-09-04 03:22:56,758 - INFO - VM name : ctest-DisablePolicyEcmpIpv6-73803047-66926664 2025-09-04 03:22:56,850 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 ID is 9ae18cde-82b3-4e42-a6b6-12140f995bdb 2025-09-04 03:22:56,850 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 03:22:56,942 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/9ae18cde-82b3-4e42-a6b6-12140f995bdb 2025-09-04 03:22:56,953 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/5a474d0e-2902-46bf-ac69-781ca797e076 2025-09-04 03:22:58,121 - 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=4.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.46 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 = 1.463/2.749/4.036/1.286 ms') 2025-09-04 03:22:58,121 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 passed 2025-09-04 03:22:58,188 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:58,188 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-66926664, IP 3634:9c:a5c6:2398:bdeb:5739:0:3, Port 22 2025-09-04 03:22:58,362 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 is ready for SSH connections 2025-09-04 03:22:58,363 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:22:58,363 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:4 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:22:58,363 - DEBUG - ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:4 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:23:18,208 - DEBUG - eth0 2025-09-04 03:23:18,208 - DEBUG - Running remote_cmd, Cmd : sysctl net.ipv6.conf.eth0.accept_dad=0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:23:18,209 - DEBUG - sysctl net.ipv6.conf.eth0.accept_dad=0 2025-09-04 03:23:18,787 - DEBUG - net.ipv6.conf.eth0.accept_dad = 0 2025-09-04 03:23:19,012 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-DisablePolicyEcmpIpv6-73803047', 'ctest-intf-rtb-83039772'](UUID 56ede790-28de-4c45-b55f-24aba4271926), prefixes : ['2c3f:abac:d70f:bc23:5886:47ba::/96'] 2025-09-04 03:23:19,080 - INFO - Added intf route table 56ede790-28de-4c45-b55f-24aba4271926 to port 291313d8-6da9-4799-9483-30239775ffb4 2025-09-04 03:23:19,080 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:23:19,081 - DEBUG - ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:23:19,574 - DEBUG - eth0 2025-09-04 03:23:19,574 - DEBUG - Running remote_cmd, Cmd : ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:23:19,575 - DEBUG - ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 2025-09-04 03:23:20,117 - DEBUG - None 2025-09-04 03:23:20,117 - DEBUG - Running remote_cmd, Cmd : ifconfig -a, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:23:20,117 - DEBUG - ifconfig -a 2025-09-04 03:23:20,688 - DEBUG - eth0 Link encap:Ethernet HWaddr 02:29:13:13:d8:6d inet addr:136.62.36.196 Bcast:136.62.36.255 Mask:255.255.255.192 inet6 addr: 3634:9c:a5c6:2398:bdeb:5739:0:4/64 Scope:Global inet6 addr: fe80::29:13ff:fe13:d86d/64 Scope:Link inet6 addr: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a/128 Scope:Global UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:462 errors:0 dropped:0 overruns:0 frame:0 TX packets:366 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:45564 (45.5 KB) TX bytes:34447 (34.4 KB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:16436 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) 2025-09-04 03:23:20,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:23:20,688 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:5 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:23:20,688 - DEBUG - ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:5 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:24:04,580 - DEBUG - eth0 2025-09-04 03:24:04,580 - DEBUG - Running remote_cmd, Cmd : sysctl net.ipv6.conf.eth0.accept_dad=0, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:24:04,580 - DEBUG - sysctl net.ipv6.conf.eth0.accept_dad=0 2025-09-04 03:24:05,202 - DEBUG - net.ipv6.conf.eth0.accept_dad = 0 2025-09-04 03:24:05,440 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-DisablePolicyEcmpIpv6-73803047', 'ctest-intf-rtb-68467867'](UUID 3c30b9eb-4722-47a6-9e22-da1cf9d22a61), prefixes : ['2c3f:abac:d70f:bc23:5886:47ba::/96'] 2025-09-04 03:24:05,510 - INFO - Added intf route table 3c30b9eb-4722-47a6-9e22-da1cf9d22a61 to port 2e507958-eb83-449b-8eeb-7371585c2c51 2025-09-04 03:24:05,510 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:24:05,511 - DEBUG - ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:24:06,008 - DEBUG - eth0 2025-09-04 03:24:06,008 - DEBUG - Running remote_cmd, Cmd : ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:24:06,008 - DEBUG - ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 2025-09-04 03:24:06,565 - DEBUG - None 2025-09-04 03:24:06,565 - DEBUG - Running remote_cmd, Cmd : ifconfig -a, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-09-04 03:24:06,565 - DEBUG - ifconfig -a 2025-09-04 03:24:07,151 - DEBUG - eth0 Link encap:Ethernet HWaddr 02:2e:50:79:58:eb inet addr:136.62.36.197 Bcast:136.62.36.255 Mask:255.255.255.192 inet6 addr: fe80::2e:50ff:fe79:58eb/64 Scope:Link inet6 addr: 3634:9c:a5c6:2398:bdeb:5739:0:5/64 Scope:Global inet6 addr: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a/128 Scope:Global UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:470 errors:0 dropped:0 overruns:0 frame:0 TX packets:408 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:45432 (45.4 KB) TX bytes:37325 (37.3 KB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:16436 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) 2025-09-04 03:24:07,217 - INFO - Policy DISABLED on VMI 5a474d0e-2902-46bf-ac69-781ca797e076 2025-09-04 03:24:07,288 - INFO - Policy DISABLED on VMI 291313d8-6da9-4799-9483-30239775ffb4 2025-09-04 03:24:07,356 - INFO - Policy DISABLED on VMI 2e507958-eb83-449b-8eeb-7371585c2c51 2025-09-04 03:24:07,372 - INFO - ECMP routes found in agent 10.0.0.242, for prefix 2c3f:abac:d70f:bc23:5886:47ba::/96 2025-09-04 03:24:07,372 - INFO - Tap interface tap291313d8-6d found in ecmp routes in agent 10.0.0.242 2025-09-04 03:24:07,372 - INFO - Tap interface tap2e507958-eb found in ecmp routes in agent 10.0.0.242 2025-09-04 03:24:07,390 - INFO - ECMP routes found in agent 10.0.0.242, for prefix 2c3f:abac:d70f:bc23:5886:47ba::/96 2025-09-04 03:24:07,483 - DEBUG - Executing command: sudo tcpdump -nni tap291313d8-6d -U '(udp and src host 3634:9c:a5c6:2398:bdeb:5739:0:3 and dst host 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a and dst port 11000)' -w /tmp/tap291313d8-6d_ctest-random-70026700.pcap 2025-09-04 03:24:07,958 - DEBUG - Executing command: sudo tcpdump -nni tap2e507958-eb -U '(udp and src host 3634:9c:a5c6:2398:bdeb:5739:0:3 and dst host 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a and dst port 11000)' -w /tmp/tap2e507958-eb_ctest-random-49549430.pcap 2025-09-04 03:24:08,054 - DEBUG - Running remote_cmd, Cmd : dd bs=100 count=1 if=/dev/zero of=icmp_error;nc -6 -q 2 -u -p 40878 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 11000 < icmp_error, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:24:08,054 - DEBUG - dd bs=100 count=1 if=/dev/zero of=icmp_error;nc -6 -q 2 -u -p 40878 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 11000 < icmp_error 2025-09-04 03:24:48,903 - DEBUG - 1+0 records in 1+0 records out 100 bytes (100 B) copied, 0.000817203 s, 122 kB/s 2025-09-04 03:24:48,903 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:48,918 - DEBUG - STDOUT: 0 2025-09-04 03:24:48,918 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:48,918 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:24:50,919 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:50,936 - DEBUG - STDOUT: 0 2025-09-04 03:24:50,936 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:50,936 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:24:52,937 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:52,952 - DEBUG - STDOUT: 0 2025-09-04 03:24:52,952 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:52,952 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:24:54,953 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:54,969 - DEBUG - STDOUT: 0 2025-09-04 03:24:54,969 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:54,970 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:24:56,970 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:56,985 - DEBUG - STDOUT: 0 2025-09-04 03:24:56,985 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:56,985 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:24:58,986 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:24:59,001 - DEBUG - STDOUT: 0 2025-09-04 03:24:59,001 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:24:59,002 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:25:01,002 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap291313d8-6d_ctest-random-70026700.pcap | grep -c length 2025-09-04 03:25:01,018 - DEBUG - STDOUT: 0 2025-09-04 03:25:01,018 - DEBUG - STDERR: reading from file /tmp/tap291313d8-6d_ctest-random-70026700.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:25:01,018 - WARNING - No packets are found in tcpdump output file /tmp/tap291313d8-6d_ctest-random-70026700.pcap but expected some packets 2025-09-04 03:25:01,018 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-49549430.pcap | grep -c length 2025-09-04 03:25:01,035 - DEBUG - STDOUT: 1 2025-09-04 03:25:01,035 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-49549430.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:25:01,035 - INFO - 1 packets are found in tcpdump output as expected 2025-09-04 03:25:01,035 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:25:03,079 - INFO - Tcpdump verification on VM 3634:9c:a5c6:2398:bdeb:5739:0:5 passed 2025-09-04 03:25:03,352 - DEBUG - Forward and reverse flows for match {'sip': '3634:9c:a5c6:2398:bdeb:5739:0:3', 'dip': '2c3f:abac:d70f:bc23:5886:47ba:eff4:773a', 'sport': '40878', 'dport': '11000', 'proto': '17', 'vrf_id': '2'}: 0, 0 2025-09-04 03:25:03,353 - DEBUG - Flow verification PASSED on node: 10.0.0.242 for VMs - Sender: 3634:9c:a5c6:2398:bdeb:5739:0:3, Receiver: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, Flow count expected: 0 0, got: 0 0 2025-09-04 03:25:03,353 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:25:05,363 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:25:07,681 - DEBUG - Forward and reverse flows for match {'sip': '3634:9c:a5c6:2398:bdeb:5739:0:3', 'dip': '2c3f:abac:d70f:bc23:5886:47ba:eff4:773a', 'sport': '40878', 'dport': '11000', 'proto': '17', 'vrf_id': '2'}: 0, 0 2025-09-04 03:25:07,682 - DEBUG - Flow verification PASSED on node: 10.0.0.241 for VMs - Sender: 3634:9c:a5c6:2398:bdeb:5739:0:3, Receiver: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, Flow count expected: 0 0, got: 0 0 2025-09-04 03:25:07,682 - INFO - Traffic verification for ECMP passed 2025-09-04 03:25:07,682 - INFO - Waiting for VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 to be up.. 2025-09-04 03:25:07,769 - DEBUG - VM is in ACTIVE state now 2025-09-04 03:25:07,769 - INFO - VM name : ctest-DisablePolicyEcmpIpv6-73803047-55873765 2025-09-04 03:25:07,853 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 ID is 03453386-4f43-4833-8244-2312a960c704 2025-09-04 03:25:07,853 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 03:25:07,934 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/03453386-4f43-4833-8244-2312a960c704 2025-09-04 03:25:07,946 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/5953b8a6-8914-48f2-93e3-28c80e0d5af5 2025-09-04 03:25:09,105 - 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=4.05 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.422 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.422/2.234/4.047/1.812 ms') 2025-09-04 03:25:09,105 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 passed 2025-09-04 03:25:09,174 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:25:09,174 - DEBUG - Waiting to SSH to VM ctest-DisablePolicyEcmpIpv6-73803047-55873765, IP 3634:9c:a5c6:2398:bdeb:5739:0:6, Port 22 2025-09-04 03:25:09,342 - DEBUG - VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 is ready for SSH connections 2025-09-04 03:25:09,408 - INFO - Policy DISABLED on VMI 5953b8a6-8914-48f2-93e3-28c80e0d5af5 2025-09-04 03:25:09,409 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 03:25:09,409 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:6 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:09,409 - DEBUG - ifconfig -a| grep 3634:9c:a5c6:2398:bdeb:5739:0:6 -A2 -B4| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:25:50,517 - DEBUG - eth0 2025-09-04 03:25:50,517 - DEBUG - Running remote_cmd, Cmd : sysctl net.ipv6.conf.eth0.accept_dad=0, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:50,517 - DEBUG - sysctl net.ipv6.conf.eth0.accept_dad=0 2025-09-04 03:25:51,072 - DEBUG - net.ipv6.conf.eth0.accept_dad = 0 2025-09-04 03:25:51,312 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-DisablePolicyEcmpIpv6-73803047', 'ctest-intf-rtb-80639802'](UUID 2ded9c6f-6a9e-4500-8421-1626f754689f), prefixes : ['2c3f:abac:d70f:bc23:5886:47ba::/96'] 2025-09-04 03:25:51,384 - INFO - Added intf route table 2ded9c6f-6a9e-4500-8421-1626f754689f to port 5953b8a6-8914-48f2-93e3-28c80e0d5af5 2025-09-04 03:25:51,385 - DEBUG - Running remote_cmd, Cmd : ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:51,385 - DEBUG - ifconfig -a| grep -i 'hwaddr\|flags' | awk '{print $1}' | cut -d ':' -f 1 2025-09-04 03:25:51,845 - DEBUG - eth0 2025-09-04 03:25:51,845 - DEBUG - Running remote_cmd, Cmd : ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:51,846 - DEBUG - ifconfig eth0 inet6 add 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 2025-09-04 03:25:52,351 - DEBUG - None 2025-09-04 03:25:52,351 - DEBUG - Running remote_cmd, Cmd : ifconfig -a, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:52,351 - DEBUG - ifconfig -a 2025-09-04 03:25:52,870 - DEBUG - eth0 Link encap:Ethernet HWaddr 02:59:53:b8:a6:89 inet addr:136.62.36.198 Bcast:136.62.36.255 Mask:255.255.255.192 inet6 addr: fe80::59:53ff:feb8:a689/64 Scope:Link inet6 addr: 3634:9c:a5c6:2398:bdeb:5739:0:6/64 Scope:Global inet6 addr: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a/128 Scope:Global UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:595 errors:0 dropped:0 overruns:0 frame:0 TX packets:531 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:53337 (53.3 KB) TX bytes:45119 (45.1 KB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:16436 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) 2025-09-04 03:25:52,871 - INFO - Deleting VM ctest-DisablePolicyEcmpIpv6-73803047-04443537 2025-09-04 03:25:53,012 - INFO - Removed intf route table 56ede790-28de-4c45-b55f-24aba4271926 from port 291313d8-6da9-4799-9483-30239775ffb4 2025-09-04 03:25:53,042 - INFO - Deleted Interface route table 56ede790-28de-4c45-b55f-24aba4271926 2025-09-04 03:25:53,055 - INFO - ECMP routes found in agent 10.0.0.242, for prefix 2c3f:abac:d70f:bc23:5886:47ba::/96 2025-09-04 03:25:53,055 - INFO - Tap interface tap2e507958-eb found in ecmp routes in agent 10.0.0.242 2025-09-04 03:25:53,068 - INFO - ECMP routes found in agent 10.0.0.241, for prefix 2c3f:abac:d70f:bc23:5886:47ba::/96 2025-09-04 03:25:53,068 - INFO - Tap interface tap5953b8a6-89 found in ecmp routes in agent 10.0.0.241 2025-09-04 03:25:53,162 - DEBUG - Executing command: sudo tcpdump -nni tap2e507958-eb -U '(udp and src host 3634:9c:a5c6:2398:bdeb:5739:0:3 and dst host 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a and dst port 11000)' -w /tmp/tap2e507958-eb_ctest-random-11872671.pcap 2025-09-04 03:25:53,666 - DEBUG - Executing command: sudo tcpdump -nni tap5953b8a6-89 -U '(udp and src host 3634:9c:a5c6:2398:bdeb:5739:0:3 and dst host 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a and dst port 11000)' -w /tmp/tap5953b8a6-89_ctest-random-43242130.pcap 2025-09-04 03:25:53,751 - DEBUG - Running remote_cmd, Cmd : dd bs=100 count=1 if=/dev/zero of=icmp_error;nc -6 -q 2 -u -p 57276 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 11000 < icmp_error, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123 2025-09-04 03:25:53,751 - DEBUG - dd bs=100 count=1 if=/dev/zero of=icmp_error;nc -6 -q 2 -u -p 57276 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a 11000 < icmp_error 2025-09-04 03:25:56,310 - DEBUG - 1+0 records in 1+0 records out 100 bytes (100 B) copied, 0.000916263 s, 109 kB/s 2025-09-04 03:25:56,310 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:25:56,325 - DEBUG - STDOUT: 0 2025-09-04 03:25:56,325 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:25:56,325 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:25:58,326 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:25:58,341 - DEBUG - STDOUT: 0 2025-09-04 03:25:58,341 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:25:58,341 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:00,341 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:26:00,356 - DEBUG - STDOUT: 0 2025-09-04 03:26:00,356 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:00,356 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:02,357 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:26:02,372 - DEBUG - STDOUT: 0 2025-09-04 03:26:02,372 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:02,372 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:04,372 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:26:04,389 - DEBUG - STDOUT: 0 2025-09-04 03:26:04,389 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:04,389 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:06,390 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:26:06,405 - DEBUG - STDOUT: 0 2025-09-04 03:26:06,405 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:06,405 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:08,406 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap2e507958-eb_ctest-random-11872671.pcap | grep -c length 2025-09-04 03:26:08,422 - DEBUG - STDOUT: 0 2025-09-04 03:26:08,422 - DEBUG - STDERR: reading from file /tmp/tap2e507958-eb_ctest-random-11872671.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:08,422 - WARNING - No packets are found in tcpdump output file /tmp/tap2e507958-eb_ctest-random-11872671.pcap but expected some packets 2025-09-04 03:26:08,423 - DEBUG - Executing command: sudo tcpdump -nnr /tmp/tap5953b8a6-89_ctest-random-43242130.pcap | grep -c length 2025-09-04 03:26:08,437 - DEBUG - STDOUT: 1 2025-09-04 03:26:08,437 - DEBUG - STDERR: reading from file /tmp/tap5953b8a6-89_ctest-random-43242130.pcap, link-type EN10MB (Ethernet), snapshot length 262144 2025-09-04 03:26:08,437 - INFO - 1 packets are found in tcpdump output as expected 2025-09-04 03:26:08,437 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:26:10,483 - INFO - Tcpdump verification on VM 3634:9c:a5c6:2398:bdeb:5739:0:6 passed 2025-09-04 03:26:10,775 - DEBUG - Forward and reverse flows for match {'sip': '3634:9c:a5c6:2398:bdeb:5739:0:3', 'dip': '2c3f:abac:d70f:bc23:5886:47ba:eff4:773a', 'sport': '57276', 'dport': '11000', 'proto': '17', 'vrf_id': '2'}: 0, 0 2025-09-04 03:26:10,775 - DEBUG - Flow verification PASSED on node: 10.0.0.241 for VMs - Sender: 3634:9c:a5c6:2398:bdeb:5739:0:3, Receiver: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, Flow count expected: 0 0, got: 0 0 2025-09-04 03:26:10,775 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:26:12,783 - DEBUG - Executing command: sudo kill $(ps -ef|grep tcpdump | grep pcap| awk '{print $2}') 2025-09-04 03:26:15,065 - DEBUG - Forward and reverse flows for match {'sip': '3634:9c:a5c6:2398:bdeb:5739:0:3', 'dip': '2c3f:abac:d70f:bc23:5886:47ba:eff4:773a', 'sport': '57276', 'dport': '11000', 'proto': '17', 'vrf_id': '2'}: 0, 0 2025-09-04 03:26:15,065 - DEBUG - Flow verification PASSED on node: 10.0.0.241 for VMs - Sender: 3634:9c:a5c6:2398:bdeb:5739:0:3, Receiver: 2c3f:abac:d70f:bc23:5886:47ba:eff4:773a, Flow count expected: 0 0, got: 0 0 2025-09-04 03:26:15,065 - INFO - Traffic verification for ECMP passed 2025-09-04 03:26:15,066 - INFO - Deleting VM ctest-DisablePolicyEcmpIpv6-73803047-55873765 2025-09-04 03:26:15,224 - INFO - Removed intf route table 2ded9c6f-6a9e-4500-8421-1626f754689f from port 5953b8a6-8914-48f2-93e3-28c80e0d5af5 2025-09-04 03:26:15,259 - INFO - Deleted Interface route table 2ded9c6f-6a9e-4500-8421-1626f754689f 2025-09-04 03:26:15,259 - INFO - Deleting VM ctest-DisablePolicyEcmpIpv6-73803047-85740808 2025-09-04 03:26:15,400 - INFO - Removed intf route table 3c30b9eb-4722-47a6-9e22-da1cf9d22a61 from port 2e507958-eb83-449b-8eeb-7371585c2c51 2025-09-04 03:26:15,434 - INFO - Deleted Interface route table 3c30b9eb-4722-47a6-9e22-da1cf9d22a61 2025-09-04 03:26:15,435 - INFO - Deleting VM ctest-DisablePolicyEcmpIpv6-73803047-66926664 2025-09-04 03:26:15,512 - INFO - Deleting VN ctest-vn-09307698 2025-09-04 03:26:15,564 - DEBUG - VN 046304bf-09b0-4d89-acfd-3b374e859972 still in use: Unable to complete operation on network 046304bf-09b0-4d89-acfd-3b374e859972. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c8a6f52e-a52e-4b0c-a92a-4e6fe706ecba'] 2025-09-04 03:26:15,564 - WARNING - Deleting VN ctest-vn-09307698 failed..Will retry 2025-09-04 03:26:17,853 - DEBUG - Response for deleting network () 2025-09-04 03:26:17,853 - DEBUG - Requesting: http://10.0.0.57:8082/routing-instance/fdbcc80c-7e32-43e2-88a1-c8e81026aae1 2025-09-04 03:26:17,866 - DEBUG - Response Code: 404 2025-09-04 03:26:17,866 - DEBUG - Requesting: http://10.0.0.57:8082/domains 2025-09-04 03:26:17,879 - DEBUG - Requesting: http://10.0.0.57:8082/domain/e0173f19-70be-4782-8849-f3657660486c 2025-09-04 03:26:17,904 - DEBUG - Requesting: http://10.0.0.57:8082/project/fdd5a1a3-d30e-4260-869e-4359849e9a07 2025-09-04 03:26:18,011 - INFO - Validated that VN ctest-vn-09307698 is not found in API Server 2025-09-04 03:26:18,020 - DEBUG - VN ctest-vn-09307698 is not present in Agent 10.0.0.241 2025-09-04 03:26:18,030 - DEBUG - VN ctest-vn-09307698 is not present in Agent 10.0.0.242 2025-09-04 03:26:18,030 - INFO - Validated that VN ctest-vn-09307698 is not in any agent 2025-09-04 03:26:18,060 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-09307698 info 2025-09-04 03:26:19,337 - DEBUG - No XMPP flaps were noticed during the test
2025-09-04 03:26:19,338 - INFO - END TEST : test_ecmp_with_static_routes : PASSED[0:05:01]
2025-09-04 03:26:19,338 - INFO - -------------------------------------------------------------------------------- 2025-09-04 03:26:20,084 - INFO - Deleted project: ctest-DisablePolicyEcmpIpv6-73803047, ID : fdd5a1a3-d30e-4260-869e-4359849e9a07