2025-07-23 17:55:02,973 - INFO - Domain Default found not creating 2025-07-23 17:55:03,116 - INFO - Project ctest-TestPbbEvpnMacLearning-91513800 not found, creating it 2025-07-23 17:55:03,620 - INFO - Created Project:ctest-TestPbbEvpnMacLearning-91513800, ID : 807c9131-dfba-441d-9574-c8c6e27d78e3 2025-07-23 17:55:05,085 - INFO - ================================================================================ 2025-07-23 17:55:05,085 - INFO - STARTING TEST : test_mac_learning_single_isid 2025-07-23 17:55:05,086 - INFO - TEST DESCRIPTION : Test MAC learning on I-Component with single isid 2025-07-23 17:55:06,401 - DEBUG - Skipping xmpp flap check 2025-07-23 17:55:06,401 - INFO - Initial checks done. Running the testcase now 2025-07-23 17:55:06,401 - INFO - 2025-07-23 17:55:06,401 - DEBUG - Read the existing encap priority 2025-07-23 17:55:06,939 - INFO - Created.UUID is {"global-vrouter-config": {"uuid": "0a71735c-f510-4293-a245-fb100d232ac5", "href": "http://10.0.0.73:8082/global-vrouter-config/0a71735c-f510-4293-a245-fb100d232ac5"}}. MPLSoUDP is the highest priority encap 2025-07-23 17:55:07,308 - DEBUG - Response for create_network : {'network': {'id': 'f4ad7c3f-d3e0-421c-86be-471a465d16d5', 'name': 'vn1', 'tenant_id': '807c9131dfba441d9574c8c6e27d78e3', 'project_id': '807c9131dfba441d9574c8c6e27d78e3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-91513800', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 17:55:07,590 - DEBUG - Response for create_subnet : {'subnet': {'id': '123007be-d936-42ae-a198-a7d48b5800f9', 'name': '', 'tenant_id': '807c9131dfba441d9574c8c6e27d78e3', 'network_id': 'f4ad7c3f-d3e0-421c-86be-471a465d16d5', 'ip_version': 4, 'cidr': '10.10.10.0/24', 'allocation_pools': [{'start': '10.10.10.2', 'end': '10.10.10.254'}], 'gateway_ip': '10.10.10.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.10.10.2', 'tags': [], 'project_id': '807c9131dfba441d9574c8c6e27d78e3'}} 2025-07-23 17:55:07,611 - INFO - Created VN vn1 2025-07-23 17:55:07,625 - DEBUG - VN vn1 UUID is f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:55:07,874 - DEBUG - Updating Layer2 control word on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to True 2025-07-23 17:55:07,983 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:55:08,274 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:55:08,447 - INFO - Created Bridge Domain ['default-domain', 'ctest-TestPbbEvpnMacLearning-91513800', 'vn1', 'ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035'], UUID: 7a3af0ee-48df-4ed7-b28e-bfed5406449d 2025-07-23 17:55:08,448 - DEBUG - Requesting: http://10.0.0.23:8082/bridge-domains 2025-07-23 17:55:08,455 - DEBUG - Requesting: http://10.0.0.23:8082/bridge-domain/7a3af0ee-48df-4ed7-b28e-bfed5406449d 2025-07-23 17:55:08,464 - INFO - Verifications in API Server 10.0.0.23 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035 passed 2025-07-23 17:55:08,464 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-91513800', 'ctest-vmi-37721777']} 2025-07-23 17:55:08,808 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-37721777', 'id': 'ef3ace15-4611-4e8a-9ea0-aa0c75895600', 'tenant_id': '807c9131dfba441d9574c8c6e27d78e3', 'network_id': 'f4ad7c3f-d3e0-421c-86be-471a465d16d5', 'mac_address': '02:ef:3a:ce:15:46', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '10.10.10.3', 'subnet_id': '123007be-d936-42ae-a198-a7d48b5800f9'}], 'security_groups': ['2bc185e5-9c2b-4099-8e3e-1b9196a8e241'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '807c9131dfba441d9574c8c6e27d78e3'}} 2025-07-23 17:55:08,808 - DEBUG - Created port ef3ace15-4611-4e8a-9ea0-aa0c75895600 2025-07-23 17:55:08,886 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-91513800', 'ctest-vmi-05662200']} 2025-07-23 17:55:09,239 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-05662200', 'id': 'dde8c53a-b7d1-4a27-8289-d6176cdbd3dd', 'tenant_id': '807c9131dfba441d9574c8c6e27d78e3', 'network_id': 'f4ad7c3f-d3e0-421c-86be-471a465d16d5', 'mac_address': '02:dd:e8:c5:3a:b7', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '10.10.10.4', 'subnet_id': '123007be-d936-42ae-a198-a7d48b5800f9'}], 'security_groups': ['2bc185e5-9c2b-4099-8e3e-1b9196a8e241'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '807c9131dfba441d9574c8c6e27d78e3'}} 2025-07-23 17:55:09,239 - DEBUG - Created port dde8c53a-b7d1-4a27-8289-d6176cdbd3dd 2025-07-23 17:55:09,352 - DEBUG - Services list from nova: [, , , ] 2025-07-23 17:55:09,352 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3704-2', 'an-jenkins-deploy-platform-ansible-os-3704-1']} 2025-07-23 17:55:09,973 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-1) 2025-07-23 17:55:10,559 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3704-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3704-2) 2025-07-23 17:55:10,560 - INFO - Waiting for VM ctest-TestPbbEvpnMacLearning-91513800-18801679 to be up.. 2025-07-23 17:55:10,611 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-23 17:55:15,690 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:55:15,691 - INFO - VM name : ctest-TestPbbEvpnMacLearning-91513800-18801679 2025-07-23 17:55:15,773 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 ID is d42c12aa-9c1e-41bb-9d13-db69370c4a7f 2025-07-23 17:55:15,795 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 launched on Node an-jenkins-deploy-platform-ansible-os-3704-1 2025-07-23 17:55:15,890 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/d42c12aa-9c1e-41bb-9d13-db69370c4a7f 2025-07-23 17:55:16,186 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/d42c12aa-9c1e-41bb-9d13-db69370c4a7f 2025-07-23 17:55:16,226 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/ef3ace15-4611-4e8a-9ea0-aa0c75895600 2025-07-23 17:55:19,501 - 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 1028ms') 2025-07-23 17:55:19,501 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:19,517 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:23,599 - 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-07-23 17:55:23,600 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:23,613 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:27,695 - 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-07-23 17:55:27,696 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:27,713 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:31,790 - 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 1010ms') 2025-07-23 17:55:31,790 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:31,804 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:35,881 - 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-07-23 17:55:35,881 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:35,897 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:40,015 - 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 1031ms') 2025-07-23 17:55:40,015 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:40,031 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:44,112 - 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-07-23 17:55:44,112 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:44,133 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:48,234 - 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 1022ms') 2025-07-23 17:55:48,234 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 failed! 2025-07-23 17:55:48,250 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-23 17:55:50,341 - 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=10.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.17 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 = 3.165/7.031/10.897/3.866 ms') 2025-07-23 17:55:50,341 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-18801679 passed 2025-07-23 17:55:50,422 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:55:50,422 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-18801679, IP 10.10.10.3, Port 22 2025-07-23 17:55:50,509 - 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-07-23 17:55:50,620 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 17:55:55,621 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:55:55,621 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-18801679, IP 10.10.10.3, Port 22 2025-07-23 17:55:55,689 - 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-07-23 17:55:55,779 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 17:56:00,780 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:00,780 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-18801679, IP 10.10.10.3, Port 22 2025-07-23 17:56:00,870 - 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-07-23 17:56:00,959 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 17:56:05,960 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:05,960 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-18801679, IP 10.10.10.3, Port 22 2025-07-23 17:56:06,029 - 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-07-23 17:56:06,127 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 17:56:11,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:11,128 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-18801679, IP 10.10.10.3, Port 22 2025-07-23 17:56:11,342 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-18801679 is ready for SSH connections 2025-07-23 17:56:11,342 - INFO - Waiting for VM ctest-TestPbbEvpnMacLearning-91513800-26651915 to be up.. 2025-07-23 17:56:11,429 - DEBUG - VM is in ACTIVE state now 2025-07-23 17:56:11,430 - INFO - VM name : ctest-TestPbbEvpnMacLearning-91513800-26651915 2025-07-23 17:56:11,517 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-26651915 ID is ef2957cf-3d06-4ff7-8848-ef7b8b7f90a1 2025-07-23 17:56:11,517 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-26651915 launched on Node an-jenkins-deploy-platform-ansible-os-3704-2 2025-07-23 17:56:11,599 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/ef2957cf-3d06-4ff7-8848-ef7b8b7f90a1 2025-07-23 17:56:11,609 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/dde8c53a-b7d1-4a27-8289-d6176cdbd3dd 2025-07-23 17:56:12,782 - 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.89 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.64 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 = 2.642/4.267/5.892/1.625 ms') 2025-07-23 17:56:12,782 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-91513800-26651915 passed 2025-07-23 17:56:12,852 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:12,852 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-91513800-26651915, IP 10.10.10.4, Port 22 2025-07-23 17:56:13,024 - DEBUG - VM ctest-TestPbbEvpnMacLearning-91513800-26651915 is ready for SSH connections 2025-07-23 17:56:13,024 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:13,025 - DEBUG - Running remote_cmd, Cmd : echo "10.10.10.3 ctest-TestPbbEvpnMacLearning-91513800-18801679" >> /etc/hosts, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-07-23 17:56:13,025 - DEBUG - echo "10.10.10.3 ctest-TestPbbEvpnMacLearning-91513800-18801679" >> /etc/hosts 2025-07-23 17:56:32,383 - DEBUG - None 2025-07-23 17:56:32,383 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:56:32,383 - DEBUG - Running remote_cmd, Cmd : echo "10.10.10.4 ctest-TestPbbEvpnMacLearning-91513800-26651915" >> /etc/hosts, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-07-23 17:56:32,384 - DEBUG - echo "10.10.10.4 ctest-TestPbbEvpnMacLearning-91513800-26651915" >> /etc/hosts 2025-07-23 17:57:09,291 - DEBUG - None 2025-07-23 17:57:09,291 - DEBUG - Updating PBB EVPN on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to True 2025-07-23 17:57:09,395 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:09,410 - DEBUG - Updating PBB etree on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to False 2025-07-23 17:57:09,511 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:09,619 - INFO - Setting flood_unknown_unicast flag of VN vn1 to True 2025-07-23 17:57:09,619 - DEBUG - Updating MAC Learning on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to True 2025-07-23 17:57:09,723 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:09,738 - DEBUG - Updating MAC Limit control on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to mac_limit = 1024, mac_limit_action = log 2025-07-23 17:57:09,835 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:09,850 - DEBUG - Updating MAC Move on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to mac_move_limit = 1024, mac_move_time_window = 30, mac_move_limit_action = log 2025-07-23 17:57:09,943 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:09,956 - DEBUG - Updating MAC Aging on VN default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1 to 300 2025-07-23 17:57:10,060 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/f4ad7c3f-d3e0-421c-86be-471a465d16d5 2025-07-23 17:57:10,118 - INFO - Adding Bridge Domain 7a3af0ee-48df-4ed7-b28e-bfed5406449d to VMI ef3ace15-4611-4e8a-9ea0-aa0c75895600 2025-07-23 17:57:10,271 - INFO - Verification for Bridge Domain uuid 7a3af0ee-48df-4ed7-b28e-bfed5406449d for VMI ef3ace15-4611-4e8a-9ea0-aa0c75895600 passed in agent 10.0.0.50 2025-07-23 17:57:10,325 - INFO - Verifications in Agent 10.0.0.50 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035 for VN info passed 2025-07-23 17:57:10,325 - INFO - Adding Bridge Domain 7a3af0ee-48df-4ed7-b28e-bfed5406449d to VMI dde8c53a-b7d1-4a27-8289-d6176cdbd3dd 2025-07-23 17:57:10,465 - INFO - Verification for Bridge Domain uuid 7a3af0ee-48df-4ed7-b28e-bfed5406449d for VMI dde8c53a-b7d1-4a27-8289-d6176cdbd3dd passed in agent 10.0.0.130 2025-07-23 17:57:10,526 - INFO - Verifications in Agent 10.0.0.130 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035 for VN info passed 2025-07-23 17:59:10,526 - DEBUG - Running remote_cmd, Cmd : test -f /etc/redhat-release && ifconfig | grep -i -B 2 "02:ef:3a:ce:15:46" | grep flags | awk '{print \\$1}' || ifconfig | grep "02:ef:3a:ce:15:46" | awk '{print $1}' | head -1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-07-23 17:59:10,527 - DEBUG - test -f /etc/redhat-release && ifconfig | grep -i -B 2 "02:ef:3a:ce:15:46" | grep flags | awk '{print \\$1}' || ifconfig | grep "02:ef:3a:ce:15:46" | awk '{print $1}' | head -1 2025-07-23 17:59:10,979 - DEBUG - eth0 2025-07-23 17:59:11,236 - DEBUG - file transfer done ['/tmp/program.py'] 2025-07-23 17:59:11,236 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 17:59:11,236 - DEBUG - Copied file /tmp/tmpjmetfm45/program.py to 10.10.10.3 2025-07-23 17:59:11,236 - DEBUG - Running remote_cmd, Cmd : python /tmp/program.py 1>/tmp/scapy_ctest-random-08800046.pid_stdout.log 2>/tmp/scapy_ctest-random-08800046.pid_stderr.log, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-07-23 17:59:11,236 - DEBUG - nohup python /tmp/program.py 1>/tmp/scapy_ctest-random-08800046.pid_stdout.log 2>/tmp/scapy_ctest-random-08800046.pid_stderr.log & echo $! > /tmp/scapy_ctest-random-08800046.pid 2025-07-23 17:59:11,736 - DEBUG - None 2025-07-23 17:59:16,811 - DEBUG - L2 routes found in agent is: {'mac': '00:16:3e:54:28:4a', 'routes': [{'mac': '00:16:3e:54:28:4a', 'src_vrf': 'default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1:vn1:7a3af0ee-48df-4ed7-b28e-bfed5406449d', 'path_list': [{'nh': {'type': 'interface', 'ref_count': '13', 'valid': 'true', 'policy': 'enabled', 'itf': 'tapef3ace15-46', 'mac': ['2:ef:3a:ce:15:46'], 'mcast': 'disabled', 'nh_index': '36', 'vxlan_flag': 'false', 'intf_flags': '2', 'isid': '0', 'learning_enabled': 'true', 'etree_leaf': 'false', 'layer2_control_word': 'true', 'crypt_all_traffic': 'false', 'crypt_path_available': 'false', 'crypt_interface': None}, 'label': '31', 'vxlan_id': '12', 'peer': 'EVPN', 'dest_vn_list': ['default-domain:ctest-TestPbbEvpnMacLearning-91513800:vn1'], 'unresolved': 'false', 'sg_list': ['8000002'], 'supported_tunnel_type': 'MPLSoGRE MPLSoUDP VxLAN', 'active_tunnel_type': 'MPLSoUDP', 'stale': 'false', 'path_preference_data': {'sequence': '0', 'preference': '100', 'ecmp': 'false', 'wait_for_traffic': 'true'}, 'info': '0-00:16:3e:54:28:4a-0.0.0.0/32 DynamicMacLearningPeer', 'active_label': '31', 'ecmp_hashing_fields': 'l3-source-address,l3-destination-address,l4-protocol,l4-source-port,l4-destination-port,', 'communities': None, 'peer_sequence_number': '0', 'etree_leaf': 'false', 'layer2_control_word': 'true', 'tag_list': None, 'inactive': 'false', 'origin_vn': None}]}]} 2025-07-23 17:59:16,818 - INFO - Detaching port dde8c53a-b7d1-4a27-8289-d6176cdbd3dd from VM ctest-TestPbbEvpnMacLearning-91513800-26651915 2025-07-23 17:59:16,854 - INFO - Deleting VM ctest-TestPbbEvpnMacLearning-91513800-26651915 2025-07-23 17:59:16,915 - INFO - Detaching port ef3ace15-4611-4e8a-9ea0-aa0c75895600 from VM ctest-TestPbbEvpnMacLearning-91513800-18801679 2025-07-23 17:59:16,946 - INFO - Deleting VM ctest-TestPbbEvpnMacLearning-91513800-18801679 2025-07-23 17:59:17,258 - DEBUG - Response for delete_port : () 2025-07-23 17:59:17,258 - INFO - Deleted port dde8c53a-b7d1-4a27-8289-d6176cdbd3dd 2025-07-23 17:59:17,453 - DEBUG - Response for delete_port : () 2025-07-23 17:59:17,453 - INFO - Deleted port ef3ace15-4611-4e8a-9ea0-aa0c75895600 2025-07-23 17:59:17,489 - INFO - Deleted Bridge Domain 7a3af0ee-48df-4ed7-b28e-bfed5406449d 2025-07-23 17:59:17,495 - INFO - Bridge Domain ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035 is removed from Agent 10.0.0.50 2025-07-23 17:59:17,502 - INFO - Bridge Domain ctest-bd_ctest-TestPbbEvpnMacLearning-91513800-91205035 is removed from Agent 10.0.0.130 2025-07-23 17:59:17,502 - DEBUG - Deleting RT for VN vn1 2025-07-23 17:59:17,659 - INFO - Deleting VN vn1 2025-07-23 17:59:17,896 - DEBUG - Response for deleting network () 2025-07-23 17:59:19,153 - ERROR -
Traceback (most recent call last):
  File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper
    result = function(self, *args, **kwargs)
  File "/contrail-test/serial_scripts/pbb_evpn/test_pbb_evpn.py", line 83, in test_mac_learning_single_isid
    cmac=stream['src_cmac'])
  File "/contrail-test/common/pbb_evpn/base.py", line 634, in verify_mac_learning
    vmi_fixture.mac_address, nh_type, mpls_label, isid)
  File "/contrail-test/common/pbb_evpn/base.py", line 729, in validate_pbb_l2_route
    'mac']) != EUI(bmac)):
  File "/usr/local/lib/python3.6/site-packages/netaddr/eui/__init__.py", line 387, in __init__
    self.value = addr
  File "/usr/local/lib/python3.6/site-packages/netaddr/eui/__init__.py", line 423, in _set_value
    self._value = module.str_to_int(value)
  File "/usr/local/lib/python3.6/site-packages/netaddr/strategy/eui48.py", line 178, in str_to_int
    raise TypeError('%r is not str() or unicode()!' % addr)
TypeError: ['2:ef:3a:ce:15:46'] is not str() or unicode()!
2025-07-23 17:59:19,153 - DEBUG - Skipping xmpp flap check 2025-07-23 17:59:19,153 - INFO -
2025-07-23 17:59:19,153 - INFO - END TEST : test_mac_learning_single_isid : FAILED[0:04:14]
2025-07-23 17:59:19,153 - INFO - -------------------------------------------------------------------------------- 2025-07-23 17:59:19,921 - INFO - Deleted project: ctest-TestPbbEvpnMacLearning-91513800, ID : 807c9131-dfba-441d-9574-c8c6e27d78e3