2025-07-22 18:09:07,341 - INFO - Domain Default found not creating 2025-07-22 18:09:07,501 - INFO - Project ctest-TestPbbEvpnMacLearning-25636690 not found, creating it 2025-07-22 18:09:08,019 - INFO - Created Project:ctest-TestPbbEvpnMacLearning-25636690, ID : 59c0beaf-3f38-44d2-aa98-e477130191c5 2025-07-22 18:09:09,493 - INFO - ================================================================================ 2025-07-22 18:09:09,493 - INFO - STARTING TEST : test_mac_learning_single_isid 2025-07-22 18:09:09,493 - INFO - TEST DESCRIPTION : Test MAC learning on I-Component with single isid 2025-07-22 18:09:10,749 - DEBUG - Skipping xmpp flap check 2025-07-22 18:09:10,750 - INFO - Initial checks done. Running the testcase now 2025-07-22 18:09:10,750 - INFO - 2025-07-22 18:09:10,750 - DEBUG - Read the existing encap priority 2025-07-22 18:09:11,280 - INFO - Created.UUID is {"global-vrouter-config": {"uuid": "741f8ac2-b0a6-4633-a17b-a5bc4fedfd9e", "href": "http://10.0.0.23:8082/global-vrouter-config/741f8ac2-b0a6-4633-a17b-a5bc4fedfd9e"}}. MPLSoUDP is the highest priority encap 2025-07-22 18:09:11,661 - DEBUG - Response for create_network : {'network': {'id': '5e92b162-d20d-48bd-a6b2-302379a06bd3', 'name': 'vn1', 'tenant_id': '59c0beaf3f3844d2aa98e477130191c5', 'project_id': '59c0beaf3f3844d2aa98e477130191c5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-25636690', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-07-22 18:09:11,964 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cafc8af7-f3d1-4316-9856-ef86433397c7', 'name': '', 'tenant_id': '59c0beaf3f3844d2aa98e477130191c5', 'network_id': '5e92b162-d20d-48bd-a6b2-302379a06bd3', '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': '59c0beaf3f3844d2aa98e477130191c5'}} 2025-07-22 18:09:11,988 - INFO - Created VN vn1 2025-07-22 18:09:12,006 - DEBUG - VN vn1 UUID is 5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:09:12,279 - DEBUG - Updating Layer2 control word on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to True 2025-07-22 18:09:12,396 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:09:12,693 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:09:12,872 - INFO - Created Bridge Domain ['default-domain', 'ctest-TestPbbEvpnMacLearning-25636690', 'vn1', 'ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084'], UUID: 3d538441-32fb-4b91-952a-fd26cc74eb3e 2025-07-22 18:09:12,872 - DEBUG - Requesting: http://10.0.0.254:8082/bridge-domains 2025-07-22 18:09:12,882 - DEBUG - Requesting: http://10.0.0.254:8082/bridge-domain/3d538441-32fb-4b91-952a-fd26cc74eb3e 2025-07-22 18:09:12,893 - INFO - Verifications in API Server 10.0.0.254 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084 passed 2025-07-22 18:09:12,893 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-25636690', 'ctest-vmi-06812654']} 2025-07-22 18:09:13,246 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-06812654', 'id': '4cb4a5f1-fcf8-4420-b398-345b90125b7c', 'tenant_id': '59c0beaf3f3844d2aa98e477130191c5', 'network_id': '5e92b162-d20d-48bd-a6b2-302379a06bd3', 'mac_address': '02:4c:b4:a5:f1:fc', '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': 'cafc8af7-f3d1-4316-9856-ef86433397c7'}], 'security_groups': ['a72af58e-646d-49d7-9244-47d201f2e2c8'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '59c0beaf3f3844d2aa98e477130191c5'}} 2025-07-22 18:09:13,246 - DEBUG - Created port 4cb4a5f1-fcf8-4420-b398-345b90125b7c 2025-07-22 18:09:13,317 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPbbEvpnMacLearning-25636690', 'ctest-vmi-05911883']} 2025-07-22 18:09:13,690 - DEBUG - Response for create_port : {'port': {'name': 'ctest-vmi-05911883', 'id': 'ad1eeeaf-8654-48eb-92f4-e5434fb146be', 'tenant_id': '59c0beaf3f3844d2aa98e477130191c5', 'network_id': '5e92b162-d20d-48bd-a6b2-302379a06bd3', 'mac_address': '02:ad:1e:ee:af:86', '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': 'cafc8af7-f3d1-4316-9856-ef86433397c7'}], 'security_groups': ['a72af58e-646d-49d7-9244-47d201f2e2c8'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '59c0beaf3f3844d2aa98e477130191c5'}} 2025-07-22 18:09:13,690 - DEBUG - Created port ad1eeeaf-8654-48eb-92f4-e5434fb146be 2025-07-22 18:09:13,792 - DEBUG - Services list from nova: [, , , ] 2025-07-22 18:09:13,792 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3693-2', 'an-jenkins-deploy-platform-ansible-os-3693-1']} 2025-07-22 18:09:14,377 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-1) 2025-07-22 18:09:15,038 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3693-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3693-2) 2025-07-22 18:09:15,038 - INFO - Waiting for VM ctest-TestPbbEvpnMacLearning-25636690-61051626 to be up.. 2025-07-22 18:09:15,212 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-22 18:09:20,317 - DEBUG - VM is in ACTIVE state now 2025-07-22 18:09:20,317 - INFO - VM name : ctest-TestPbbEvpnMacLearning-25636690-61051626 2025-07-22 18:09:20,402 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 ID is d5ae819b-7e53-4c46-a6d4-63417ba42f02 2025-07-22 18:09:20,430 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 launched on Node an-jenkins-deploy-platform-ansible-os-3693-1 2025-07-22 18:09:20,526 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d5ae819b-7e53-4c46-a6d4-63417ba42f02 2025-07-22 18:09:20,843 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d5ae819b-7e53-4c46-a6d4-63417ba42f02 2025-07-22 18:09:20,881 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/4cb4a5f1-fcf8-4420-b398-345b90125b7c 2025-07-22 18:09:24,067 - 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-07-22 18:09:24,067 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:24,084 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:28,163 - 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 1018ms') 2025-07-22 18:09:28,163 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:28,198 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:32,292 - 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 1029ms') 2025-07-22 18:09:32,292 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:32,310 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:36,383 - 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 1011ms') 2025-07-22 18:09:36,383 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:36,400 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:40,481 - 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-22 18:09:40,481 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:40,512 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:44,576 - 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 1004ms') 2025-07-22 18:09:44,576 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:44,592 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:48,674 - 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 1019ms') 2025-07-22 18:09:48,674 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:48,690 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:52,773 - 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 1019ms') 2025-07-22 18:09:52,773 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 failed! 2025-07-22 18:09:52,791 - DEBUG - Gateway for vn default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 is 10.10.10.1 and allocation pool is NOT set 2025-07-22 18:09:54,855 - 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.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.42 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 = 3.416/4.096/4.777/0.680 ms') 2025-07-22 18:09:54,855 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-61051626 passed 2025-07-22 18:09:54,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:09:54,931 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-61051626, IP 10.10.10.3, Port 22 2025-07-22 18:09:54,999 - 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-22 18:09:55,101 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-22 18:10:00,102 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:00,102 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-61051626, IP 10.10.10.3, Port 22 2025-07-22 18:10:00,170 - 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-22 18:10:00,260 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-22 18:10:05,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:05,260 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-61051626, IP 10.10.10.3, Port 22 2025-07-22 18:10:05,327 - 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-22 18:10:05,423 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-22 18:10:10,424 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:10,424 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-61051626, IP 10.10.10.3, Port 22 2025-07-22 18:10:10,481 - 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-22 18:10:10,574 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-22 18:10:15,575 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:15,575 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-61051626, IP 10.10.10.3, Port 22 2025-07-22 18:10:15,746 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-61051626 is ready for SSH connections 2025-07-22 18:10:15,747 - INFO - Waiting for VM ctest-TestPbbEvpnMacLearning-25636690-10974504 to be up.. 2025-07-22 18:10:15,835 - DEBUG - VM is in ACTIVE state now 2025-07-22 18:10:15,835 - INFO - VM name : ctest-TestPbbEvpnMacLearning-25636690-10974504 2025-07-22 18:10:15,918 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-10974504 ID is 4fd1d9d5-e513-4129-8988-93372315429a 2025-07-22 18:10:15,919 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-10974504 launched on Node an-jenkins-deploy-platform-ansible-os-3693-2 2025-07-22 18:10:16,000 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4fd1d9d5-e513-4129-8988-93372315429a 2025-07-22 18:10:16,013 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/ad1eeeaf-8654-48eb-92f4-e5434fb146be 2025-07-22 18:10:17,196 - 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.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.94 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.938/3.111/3.285/0.173 ms') 2025-07-22 18:10:17,196 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestPbbEvpnMacLearning-25636690-10974504 passed 2025-07-22 18:10:17,270 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:17,270 - DEBUG - Waiting to SSH to VM ctest-TestPbbEvpnMacLearning-25636690-10974504, IP 10.10.10.4, Port 22 2025-07-22 18:10:17,439 - DEBUG - VM ctest-TestPbbEvpnMacLearning-25636690-10974504 is ready for SSH connections 2025-07-22 18:10:17,439 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:17,439 - DEBUG - Running remote_cmd, Cmd : echo "10.10.10.3 ctest-TestPbbEvpnMacLearning-25636690-61051626" >> /etc/hosts, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-07-22 18:10:17,439 - DEBUG - echo "10.10.10.3 ctest-TestPbbEvpnMacLearning-25636690-61051626" >> /etc/hosts 2025-07-22 18:10:37,547 - DEBUG - None 2025-07-22 18:10:37,547 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:10:37,547 - DEBUG - Running remote_cmd, Cmd : echo "10.10.10.4 ctest-TestPbbEvpnMacLearning-25636690-10974504" >> /etc/hosts, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-22 18:10:37,548 - DEBUG - echo "10.10.10.4 ctest-TestPbbEvpnMacLearning-25636690-10974504" >> /etc/hosts 2025-07-22 18:11:17,043 - DEBUG - None 2025-07-22 18:11:17,044 - DEBUG - Updating PBB EVPN on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to True 2025-07-22 18:11:17,184 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:17,198 - DEBUG - Updating PBB etree on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to False 2025-07-22 18:11:17,329 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:17,476 - INFO - Setting flood_unknown_unicast flag of VN vn1 to True 2025-07-22 18:11:17,476 - DEBUG - Updating MAC Learning on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to True 2025-07-22 18:11:17,592 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:17,612 - DEBUG - Updating MAC Limit control on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to mac_limit = 1024, mac_limit_action = log 2025-07-22 18:11:17,736 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:17,752 - DEBUG - Updating MAC Move on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to mac_move_limit = 1024, mac_move_time_window = 30, mac_move_limit_action = log 2025-07-22 18:11:17,872 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:17,889 - DEBUG - Updating MAC Aging on VN default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1 to 300 2025-07-22 18:11:18,012 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/5e92b162-d20d-48bd-a6b2-302379a06bd3 2025-07-22 18:11:18,028 - INFO - Adding Bridge Domain 3d538441-32fb-4b91-952a-fd26cc74eb3e to VMI 4cb4a5f1-fcf8-4420-b398-345b90125b7c 2025-07-22 18:11:18,172 - INFO - Verification for Bridge Domain uuid 3d538441-32fb-4b91-952a-fd26cc74eb3e for VMI 4cb4a5f1-fcf8-4420-b398-345b90125b7c passed in agent 10.0.0.19 2025-07-22 18:11:18,215 - INFO - Verifications in Agent 10.0.0.19 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084 for VN info passed 2025-07-22 18:11:18,215 - INFO - Adding Bridge Domain 3d538441-32fb-4b91-952a-fd26cc74eb3e to VMI ad1eeeaf-8654-48eb-92f4-e5434fb146be 2025-07-22 18:11:18,357 - INFO - Verification for Bridge Domain uuid 3d538441-32fb-4b91-952a-fd26cc74eb3e for VMI ad1eeeaf-8654-48eb-92f4-e5434fb146be passed in agent 10.0.0.27 2025-07-22 18:11:18,405 - INFO - Verifications in Agent 10.0.0.27 for BD ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084 for VN info passed 2025-07-22 18:13:18,406 - DEBUG - Running remote_cmd, Cmd : test -f /etc/redhat-release && ifconfig | grep -i -B 2 "02:4c:b4:a5:f1:fc" | grep flags | awk '{print \\$1}' || ifconfig | grep "02:4c:b4:a5:f1:fc" | awk '{print $1}' | head -1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-07-22 18:13:18,407 - DEBUG - test -f /etc/redhat-release && ifconfig | grep -i -B 2 "02:4c:b4:a5:f1:fc" | grep flags | awk '{print \\$1}' || ifconfig | grep "02:4c:b4:a5:f1:fc" | awk '{print $1}' | head -1 2025-07-22 18:13:18,885 - DEBUG - eth0 2025-07-22 18:13:19,074 - DEBUG - file transfer done ['/tmp/program.py'] 2025-07-22 18:13:19,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-22 18:13:19,074 - DEBUG - Copied file /tmp/tmpbcly85cj/program.py to 10.10.10.3 2025-07-22 18:13:19,074 - DEBUG - Running remote_cmd, Cmd : python /tmp/program.py 1>/tmp/scapy_ctest-random-23291491.pid_stdout.log 2>/tmp/scapy_ctest-random-23291491.pid_stderr.log, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-07-22 18:13:19,074 - DEBUG - nohup python /tmp/program.py 1>/tmp/scapy_ctest-random-23291491.pid_stdout.log 2>/tmp/scapy_ctest-random-23291491.pid_stderr.log & echo $! > /tmp/scapy_ctest-random-23291491.pid 2025-07-22 18:13:19,563 - DEBUG - None 2025-07-22 18:13:24,614 - DEBUG - L2 routes found in agent is: {'mac': '00:16:3e:76:5d:e0', 'routes': [{'mac': '00:16:3e:76:5d:e0', 'src_vrf': 'default-domain:ctest-TestPbbEvpnMacLearning-25636690:vn1:vn1:3d538441-32fb-4b91-952a-fd26cc74eb3e', 'path_list': [{'nh': {'type': 'interface', 'ref_count': '13', 'valid': 'true', 'policy': 'enabled', 'itf': 'tap4cb4a5f1-fc', 'mac': ['2:4c:b4:a5:f1:fc'], '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-25636690:vn1'], 'unresolved': 'false', 'sg_list': ['8000006'], '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:76:5d:e0-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-22 18:13:24,622 - INFO - Detaching port ad1eeeaf-8654-48eb-92f4-e5434fb146be from VM ctest-TestPbbEvpnMacLearning-25636690-10974504 2025-07-22 18:13:24,690 - INFO - Deleting VM ctest-TestPbbEvpnMacLearning-25636690-10974504 2025-07-22 18:13:24,815 - INFO - Detaching port 4cb4a5f1-fcf8-4420-b398-345b90125b7c from VM ctest-TestPbbEvpnMacLearning-25636690-61051626 2025-07-22 18:13:24,853 - INFO - Deleting VM ctest-TestPbbEvpnMacLearning-25636690-61051626 2025-07-22 18:13:25,250 - DEBUG - Response for delete_port : () 2025-07-22 18:13:25,250 - INFO - Deleted port ad1eeeaf-8654-48eb-92f4-e5434fb146be 2025-07-22 18:13:25,561 - DEBUG - Response for delete_port : () 2025-07-22 18:13:25,561 - INFO - Deleted port 4cb4a5f1-fcf8-4420-b398-345b90125b7c 2025-07-22 18:13:25,622 - INFO - Deleted Bridge Domain 3d538441-32fb-4b91-952a-fd26cc74eb3e 2025-07-22 18:13:25,629 - INFO - Bridge Domain ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084 is removed from Agent 10.0.0.19 2025-07-22 18:13:25,639 - INFO - Bridge Domain ctest-bd_ctest-TestPbbEvpnMacLearning-25636690-69492084 is removed from Agent 10.0.0.27 2025-07-22 18:13:25,639 - DEBUG - Deleting RT for VN vn1 2025-07-22 18:13:25,836 - INFO - Deleting VN vn1 2025-07-22 18:13:26,171 - DEBUG - Response for deleting network () 2025-07-22 18:13:27,489 - 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:4c:b4:a5:f1:fc'] is not str() or unicode()!
2025-07-22 18:13:27,490 - DEBUG - Skipping xmpp flap check 2025-07-22 18:13:27,490 - INFO -
2025-07-22 18:13:27,490 - INFO - END TEST : test_mac_learning_single_isid : FAILED[0:04:18]
2025-07-22 18:13:27,490 - INFO - -------------------------------------------------------------------------------- 2025-07-22 18:13:28,470 - INFO - Deleted project: ctest-TestPbbEvpnMacLearning-25636690, ID : 59c0beaf-3f38-44d2-aa98-e477130191c5