2025-07-31 05:51:34,112 - INFO - Domain Default found not creating
2025-07-31 05:51:34,482 - INFO - Project ctest-TestBasicVMVN0-68332753 not found, creating it
2025-07-31 05:51:35,112 - INFO - Created Project:ctest-TestBasicVMVN0-68332753, ID : ac5f5468-d7a8-4019-8ee1-9a411ff512b3
2025-07-31 05:51:37,389 - INFO - ================================================================================
2025-07-31 05:51:37,389 - INFO - STARTING TEST : test_control_node_switchover
2025-07-31 05:51:37,390 - INFO - TEST DESCRIPTION : Stop the control node and check peering with agent fallback to other control node.
1. Pick one VN from respource pool which has 2 VM's in it
2. Verify ping between VM's
3. Find active control node in cluster by agent inspect
4. Stop control service on active control node
5. Verify agents are connected to new active control-node using xmpp connections
6. Bring back control service on previous active node
7. Verify ping between VM's again after bringing up control serveice
Pass criteria: Step 2,5 and 7 should pass
2025-07-31 05:51:38,755 - DEBUG - Skipping xmpp flap check
2025-07-31 05:51:38,756 - INFO - Initial checks done. Running the testcase now
2025-07-31 05:51:38,756 - INFO -
2025-07-31 05:51:39,642 - DEBUG - Response for create_network : {'network': {'id': '304e52d1-73ef-4b44-a26e-0f79c9c721f6', 'name': 'ctest-vn1-27189330', 'tenant_id': 'ac5f5468d7a840198ee19a411ff512b3', 'project_id': 'ac5f5468d7a840198ee19a411ff512b3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN0-68332753', 'ctest-vn1-27189330'], 'port_security_enabled': True, 'description': ''}}
2025-07-31 05:51:39,980 - DEBUG - Response for create_subnet : {'subnet': {'id': '93ffe374-0d29-4b01-a040-84442f6e5fe0', 'name': '', 'tenant_id': 'ac5f5468d7a840198ee19a411ff512b3', 'network_id': '304e52d1-73ef-4b44-a26e-0f79c9c721f6', 'ip_version': 4, 'cidr': '192.168.1.0/24', 'allocation_pools': [{'start': '192.168.1.2', 'end': '192.168.1.254'}], 'gateway_ip': '192.168.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '192.168.1.2', 'tags': [], 'project_id': 'ac5f5468d7a840198ee19a411ff512b3'}}
2025-07-31 05:51:40,010 - INFO - Created VN ctest-vn1-27189330
2025-07-31 05:51:40,026 - DEBUG - VN ctest-vn1-27189330 UUID is 304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,118 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 05:51:40,454 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 05:51:40,518 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 05:51:40,570 - DEBUG - Requesting: http://10.0.0.41:8082/project/ac5f5468-d7a8-4019-8ee1-9a411ff512b3
2025-07-31 05:51:40,693 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,716 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,745 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:51:40,764 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:51:40,782 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/06e98230-0c61-451c-a8de-014345481980
2025-07-31 05:51:40,797 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:51:40,797 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,817 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:51:40,846 - INFO - Verified VN network id 13 for VN 304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,846 - INFO - Verifications in API Server for VN ctest-vn1-27189330 passed
2025-07-31 05:51:40,846 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:40,867 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:51:40,878 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:51:40,889 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/06e98230-0c61-451c-a8de-014345481980
2025-07-31 05:51:40,916 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN0-68332753', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3480810621550676804', 'uuid-lslong': '11704309497333228022'}, 'enable': 'true', 'created': '2025-07-31T05:51:39', 'last-modified': '2025-07-31T05:51:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.002002'}
2025-07-31 05:51:40,930 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:51:40,938 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN0-68332753', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3480810621550676804', 'uuid-lslong': '11704309497333228022'}, 'enable': 'true', 'created': '2025-07-31T05:51:39', 'last-modified': '2025-07-31T05:51:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.033611'}
2025-07-31 05:51:40,949 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:51:40,959 - DEBUG - Control-node 10.0.0.61 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN0-68332753', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3480810621550676804', 'uuid-lslong': '11704309497333228022'}, 'enable': 'true', 'created': '2025-07-31T05:51:39', 'last-modified': '2025-07-31T05:51:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.053429'}
2025-07-31 05:51:40,969 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:51:40,970 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn1-27189330 passed
2025-07-31 05:51:40,970 - DEBUG - ====Verifying policy data for ctest-vn1-27189330 in API_Server ======
2025-07-31 05:51:40,970 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 05:51:40,981 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 05:51:41,009 - DEBUG - Requesting: http://10.0.0.41:8082/project/ac5f5468-d7a8-4019-8ee1-9a411ff512b3
2025-07-31 05:51:41,156 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/304e52d1-73ef-4b44-a26e-0f79c9c721f6
2025-07-31 05:51:41,178 - DEBUG - =>VN ctest-vn1-27189330 has no policy to be verified
2025-07-31 05:51:41,178 - DEBUG - Verifying the vn in opserver
2025-07-31 05:51:41,179 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 virtual network link through opserver 10.0.0.41
2025-07-31 05:51:41,179 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-07-31 05:51:41,506 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-07-31 05:51:41,630 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330?flat'}
2025-07-31 05:51:41,631 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 virtual network link through opserver 10.0.0.38
2025-07-31 05:51:41,631 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-07-31 05:51:41,952 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks
2025-07-31 05:51:42,035 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330?flat'}
2025-07-31 05:51:42,035 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 virtual network link through opserver 10.0.0.61
2025-07-31 05:51:42,035 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-07-31 05:51:42,363 - DEBUG - Requesting: http://10.0.0.61:8081/analytics/uves/virtual-networks
2025-07-31 05:51:42,507 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330', 'href': 'http://10.0.0.61:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330?flat'}
2025-07-31 05:51:42,508 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is found in opserver
2025-07-31 05:51:42,560 - DEBUG - Do not have enough data to verify VN in agent
2025-07-31 05:51:42,590 - DEBUG - VRF ids for VN ctest-vn1-27189330: {}
2025-07-31 05:51:42,777 - DEBUG - Services list from nova: [, , , ]
2025-07-31 05:51:44,319 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3732-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3732-1)
2025-07-31 05:51:45,942 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3732-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3732-2)
2025-07-31 05:51:45,943 - INFO - Waiting for VM ctest-vn1_vm1-17812600 to be up..
2025-07-31 05:51:46,046 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-07-31 05:51:51,171 - DEBUG - VM is in ACTIVE state now
2025-07-31 05:51:51,171 - INFO - VM name : ctest-vn1_vm1-17812600
2025-07-31 05:51:51,276 - DEBUG - VM ctest-vn1_vm1-17812600 ID is 70ba7e6d-dca5-4968-a9a1-ee4fa4cd6b1a
2025-07-31 05:51:51,308 - DEBUG - VM ctest-vn1_vm1-17812600 launched on Node an-jenkins-deploy-platform-ansible-os-3732-1
2025-07-31 05:51:51,420 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/70ba7e6d-dca5-4968-a9a1-ee4fa4cd6b1a
2025-07-31 05:51:51,762 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/70ba7e6d-dca5-4968-a9a1-ee4fa4cd6b1a
2025-07-31 05:51:51,805 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/1d6863d8-05ae-4e16-9814-fccb20f637ff
2025-07-31 05:51:55,019 - 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 1030ms')
2025-07-31 05:51:55,020 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:51:55,044 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:51:59,119 - 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 1006ms')
2025-07-31 05:51:59,119 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:51:59,150 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:03,241 - 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-31 05:52:03,242 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:03,266 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:07,341 - 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-31 05:52:07,341 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:07,369 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:11,430 - 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 1000ms')
2025-07-31 05:52:11,430 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:11,456 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:15,534 - 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-31 05:52:15,535 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:15,566 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:19,663 - 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 1027ms')
2025-07-31 05:52:19,663 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:19,694 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:23,794 - 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 1034ms')
2025-07-31 05:52:23,794 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 failed!
2025-07-31 05:52:23,818 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-27189330 is 192.168.1.1 and allocation pool is NOT set
2025-07-31 05:52:25,897 - 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=8.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=7.98 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 = 7.977/8.132/8.287/0.155 ms')
2025-07-31 05:52:25,897 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm1-17812600 passed
2025-07-31 05:52:25,988 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:25,988 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-17812600, IP 192.168.1.3, Port 22
2025-07-31 05:52:26,059 - 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-31 05:52:26,223 - DEBUG - VM ctest-vn1_vm1-17812600 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:31,224 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:31,224 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-17812600, IP 192.168.1.3, Port 22
2025-07-31 05:52:31,286 - 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-31 05:52:31,481 - DEBUG - VM ctest-vn1_vm1-17812600 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:36,482 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:36,482 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-17812600, IP 192.168.1.3, Port 22
2025-07-31 05:52:36,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-31 05:52:36,859 - DEBUG - VM ctest-vn1_vm1-17812600 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:41,860 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:41,861 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-17812600, IP 192.168.1.3, Port 22
2025-07-31 05:52:41,932 - 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-31 05:52:42,089 - DEBUG - VM ctest-vn1_vm1-17812600 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:47,090 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:47,090 - DEBUG - Waiting to SSH to VM ctest-vn1_vm1-17812600, IP 192.168.1.3, Port 22
2025-07-31 05:52:47,267 - DEBUG - VM ctest-vn1_vm1-17812600 is ready for SSH connections
2025-07-31 05:52:47,267 - INFO - Waiting for VM ctest-vn1_vm2-83275941 to be up..
2025-07-31 05:52:47,424 - DEBUG - VM is in ACTIVE state now
2025-07-31 05:52:47,424 - INFO - VM name : ctest-vn1_vm2-83275941
2025-07-31 05:52:47,557 - DEBUG - VM ctest-vn1_vm2-83275941 ID is 6d6d0494-e200-4af8-85df-df6e08e086cd
2025-07-31 05:52:47,557 - DEBUG - VM ctest-vn1_vm2-83275941 launched on Node an-jenkins-deploy-platform-ansible-os-3732-2
2025-07-31 05:52:47,696 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/6d6d0494-e200-4af8-85df-df6e08e086cd
2025-07-31 05:52:47,713 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/08082acd-e7da-4170-96c2-f65987d88a9b
2025-07-31 05:52:48,936 - 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=15.3 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.09 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.094/9.175/15.257/6.081 ms')
2025-07-31 05:52:48,936 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1_vm2-83275941 passed
2025-07-31 05:52:49,027 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:49,027 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-83275941, IP 192.168.1.4, Port 22
2025-07-31 05:52:49,098 - 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-31 05:52:49,259 - DEBUG - VM ctest-vn1_vm2-83275941 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:54,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:54,261 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-83275941, IP 192.168.1.4, Port 22
2025-07-31 05:52:54,337 - 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-31 05:52:54,520 - DEBUG - VM ctest-vn1_vm2-83275941 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-31 05:52:59,522 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:59,522 - DEBUG - Waiting to SSH to VM ctest-vn1_vm2-83275941, IP 192.168.1.4, Port 22
2025-07-31 05:52:59,713 - DEBUG - VM ctest-vn1_vm2-83275941 is ready for SSH connections
2025-07-31 05:52:59,713 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:52:59,713 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 192.168.1.4, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-07-31 05:52:59,713 - DEBUG - ping -s 56 -c 3 -W 1 192.168.1.4
2025-07-31 05:53:45,486 - DEBUG - PING 192.168.1.4 (192.168.1.4) 56(84) bytes of data.
64 bytes from 192.168.1.4: icmp_req=1 ttl=64 time=14.8 ms
64 bytes from 192.168.1.4: icmp_req=2 ttl=64 time=1.33 ms
64 bytes from 192.168.1.4: icmp_req=3 ttl=64 time=1.35 ms
--- 192.168.1.4 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.338/5.840/14.832/6.358 ms
2025-07-31 05:53:45,487 - INFO - Ping to IP 192.168.1.4 from VM ctest-vn1_vm1-17812600 passed
2025-07-31 05:53:45,487 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-31 05:53:45,488 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 192.168.1.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-31 05:53:45,488 - DEBUG - ping -s 56 -c 3 -W 1 192.168.1.3
2025-07-31 05:54:35,678 - DEBUG - PING 192.168.1.3 (192.168.1.3) 56(84) bytes of data.
64 bytes from 192.168.1.3: icmp_req=1 ttl=64 time=7.09 ms
64 bytes from 192.168.1.3: icmp_req=2 ttl=64 time=1.69 ms
64 bytes from 192.168.1.3: icmp_req=3 ttl=64 time=1.52 ms
--- 192.168.1.3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.522/3.437/7.099/2.590 ms
2025-07-31 05:54:35,678 - INFO - Ping to IP 192.168.1.3 from VM ctest-vn1_vm2-83275941 passed
2025-07-31 05:54:35,688 - INFO - Active control node from the Agent 10.0.0.27 is 10.0.0.38
2025-07-31 05:54:35,688 - INFO - Stoping the Control service in 10.0.0.38
2025-07-31 05:54:36,095 - INFO - 10.0.0.38
2025-07-31 05:54:36,096 - INFO - control:inactive
2025-07-31 05:54:36,096 - INFO - Contrail services control are down on nodes 10.0.0.38
2025-07-31 05:54:36,102 - INFO - Deleting VM ctest-vn1_vm2-83275941
2025-07-31 05:54:36,259 - INFO - Deleting VM ctest-vn1_vm1-17812600
2025-07-31 05:54:36,349 - INFO - Deleting VN ctest-vn1-27189330
2025-07-31 05:54:36,416 - DEBUG - VN 304e52d1-73ef-4b44-a26e-0f79c9c721f6 still in use: Unable to complete operation on network 304e52d1-73ef-4b44-a26e-0f79c9c721f6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-e9a0c1d8-72b0-4300-b47f-52a5fc59ca82']
2025-07-31 05:54:36,416 - WARNING - Deleting VN ctest-vn1-27189330 failed..Will retry
2025-07-31 05:54:38,696 - DEBUG - Response for deleting network ()
2025-07-31 05:54:38,696 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/733d4d03-9370-45de-8752-cddabbba342d
2025-07-31 05:54:38,706 - DEBUG - Response Code: 404
2025-07-31 05:54:38,706 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 05:54:38,714 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 05:54:38,788 - DEBUG - Requesting: http://10.0.0.41:8082/project/ac5f5468-d7a8-4019-8ee1-9a411ff512b3
2025-07-31 05:54:38,890 - INFO - Validated that VN ctest-vn1-27189330 is not found in API Server
2025-07-31 05:54:38,904 - DEBUG - VN ctest-vn1-27189330 is not present in Agent 10.0.0.27
2025-07-31 05:54:38,920 - DEBUG - VN ctest-vn1-27189330 is not present in Agent 10.0.0.57
2025-07-31 05:54:38,920 - INFO - Validated that VN ctest-vn1-27189330 is not in any agent
2025-07-31 05:54:40,273 - ERROR - NameError
Python 3.6.8: /usr/bin/python3
Thu Jul 31 05:54:36 2025
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/serial_scripts/vm_regression/test_vm_serial.py in test_control_node_switchover(self=)
652 'contrail-control', [active_controller_host_ip],
653 container='control')
654 sleep(5)
655
656 # Check the control node shifted to other control node
sleep undefined
NameError: name 'sleep' is not defined
__cause__ = None
__class__ =
__context__ = None
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Name not found globally.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ("name 'sleep' is not defined",)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
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/vm_regression/test_vm_serial.py", line 654, in test_control_node_switchover
sleep(5)
NameError: name 'sleep' is not defined
2025-07-31 05:54:40,276 - DEBUG - Skipping xmpp flap check
2025-07-31 05:54:40,277 - INFO -
2025-07-31 05:54:40,278 - INFO - END TEST : test_control_node_switchover : FAILED[0:03:03]
2025-07-31 05:54:40,278 - INFO - --------------------------------------------------------------------------------
2025-07-31 05:54:40,290 - INFO - ================================================================================
2025-07-31 05:54:40,290 - INFO - STARTING TEST : test_process_restart_in_policy_between_vns
2025-07-31 05:54:40,290 - INFO - TEST DESCRIPTION : Test to validate that with policy having rule to check icmp fwding between VMs on different VNs , ping between VMs should pass
with process restarts
1. Pick 2 VN's from resource pool which has one VM each
2. Create policy with icmp allow rule between those VN's and bind it networks
3. Ping from one VM to another VM
4. Restart process 'vrouter' and 'control' on setup
5. Ping again between VM's after process restart
Pass criteria: Step 2,3,4 and 5 should pass
2025-07-31 05:54:41,647 - DEBUG - Skipping xmpp flap check
2025-07-31 05:54:41,647 - INFO - Initial checks done. Running the testcase now
2025-07-31 05:54:41,648 - INFO -
2025-07-31 05:54:41,663 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-89062862, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn2-26175813, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-07-31 05:54:41,989 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn2-26175813, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-89062862, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2025-07-31 05:54:42,577 - INFO - Created VN ctest-vn1-89062862, UUID :660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:42,974 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2025-07-31 05:54:42,981 - DEBUG - Requesting: http://10.0.0.41:8082/domain/34dad5b7-e30d-42c7-8ff4-7b0d7fa576d7
2025-07-31 05:54:42,996 - DEBUG - Requesting: http://10.0.0.41:8082/project/ac5f5468-d7a8-4019-8ee1-9a411ff512b3
2025-07-31 05:54:43,134 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:43,153 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:43,169 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/97cf50fe-51bd-4c9b-8221-54ea0640463c
2025-07-31 05:54:43,179 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/97cf50fe-51bd-4c9b-8221-54ea0640463c
2025-07-31 05:54:43,190 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b2510aef-cb25-47e6-bf42-e132ed75be55
2025-07-31 05:54:43,201 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:54:43,201 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:43,214 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/97cf50fe-51bd-4c9b-8221-54ea0640463c
2025-07-31 05:54:43,233 - INFO - Verified VN network id 13 for VN 660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:43,233 - INFO - Verifications in API Server for VN ctest-vn1-89062862 passed
2025-07-31 05:54:43,233 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/660dfdaa-3bad-4644-b384-f2e4e197d382
2025-07-31 05:54:43,245 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/97cf50fe-51bd-4c9b-8221-54ea0640463c
2025-07-31 05:54:43,258 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/97cf50fe-51bd-4c9b-8221-54ea0640463c
2025-07-31 05:54:43,275 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/b2510aef-cb25-47e6-bf42-e132ed75be55
2025-07-31 05:54:43,299 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-89062862', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN0-68332753', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7353812674153367108', 'uuid-lslong': '12935731094566196098'}, 'enable': 'true', 'created': '2025-07-31T05:54:42', 'last-modified': '2025-07-31T05:54:42', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.570688'}
2025-07-31 05:54:43,309 - DEBUG - Route Targets: ['target:64512:8000007']
2025-07-31 05:54:43,319 - INFO - Deleting VN ctest-vn1-89062862
2025-07-31 05:54:43,513 - INFO - Deleted policy policy2
2025-07-31 05:54:43,562 - INFO - Deleted policy policy1
2025-07-31 05:54:44,890 - ERROR - AttributeError
Python 3.6.8: /usr/bin/python3
Thu Jul 31 05:54:43 2025
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/serial_scripts/vm_regression/test_vm_serial.py in test_process_restart_in_policy_between_vns(self=)
397 connections=self.connections))
398 vn1_fixture = self.create_vn(vn1_name, vn1_subnets, option='contrail')
399 assert vn1_fixture.verify_on_setup()
400 vn1_fixture.bind_policies(
401 [policy1_fixture.policy_fq_name], vn1_fixture.vn_id)
vn1_fixture =
vn1_fixture.verify_on_setup = >
/contrail-test/fixtures/vn_test.py in verify_on_setup(self=)
579 "One or more verifications in API Server for VN %s failed" % (self.vn_name))
580 return result
581 if not self.verify_vn_in_control_nodes():
582 result = result and False
583 self.logger.error(
self =
self.verify_vn_in_control_nodes = .deco_retry..f_retry of >
/contrail-test/tcutils/util.py in f_retry(*args=(,), **kwargs={})
82 mtries, mdelay = tries, delay # make mutable
83
84 result = f(*args, **kwargs) # first attempt
85 rv = result
86 final = False
result undefined
f =
args = (,)
kwargs = {}
/contrail-test/fixtures/vn_test.py in verify_vn_in_control_nodes(self=)
927 for cn in self.inputs.bgp_ips:
928 cn_config_vn_obj = self.cn_inspect[cn].get_cn_config_vn(
929 vn_name=self.vn_name, project=self.project_name, domain=self.domain_name)
930 if not cn_config_vn_obj:
931 self.logger.warn('Control-node %s does not have VN %s info ' %
vn_name undefined
self =
self.vn_name = 'ctest-vn1-89062862'
project undefined
self.project_name = 'ctest-TestBasicVMVN0-68332753'
domain undefined
self.domain_name = 'default-domain'
/contrail-test/tcutils/control/cn_introspect_utils.py in get_cn_config_vn(self=, domain='default-domain', project='ctest-TestBasicVMVN0-68332753', vn_name='ctest-vn1-89062862')
139 def get_cn_config_vn(self, domain='default-domain', project='admin', vn_name='default-virtual-network'):
140 m = 'virtual-network:' + domain + ':' + project + ':' + vn_name
141 return self._get_if_map_table_entry(m)
142
143 def get_cn_config_fip_pool(self, domain='default-domain', project='admin', vn_name='default-virtual-network', fip_pool_name='default-floating-ip-pool'):
self =
self._get_if_map_table_entry = >
m = 'virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-89062862'
/contrail-test/tcutils/control/cn_introspect_utils.py in _get_if_map_table_entry(self=, match='virtual-network:default-domain:ctest-TestBasicVMVN0-68332753:ctest-vn1-89062862')
37 new_table_req = 'Snh_IFMapTableShowReq?table_name=' + table_name.group(1) + '&search_string=' + match
38 p = self.dict_get(new_table_req)
39 xp = p.xpath('./IFMapTableShowResp/ifmap_db/list/IFMapNodeShowInfo')
40 if not xp:
41 # sometime ./xpath dosen't work; work around
xp undefined
p = None
p.xpath undefined
AttributeError: 'NoneType' object has no attribute 'xpath'
__cause__ = None
__class__ =
__context__ = None
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Attribute not found.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ("'NoneType' object has no attribute 'xpath'",)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
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/vm_regression/test_vm_serial.py", line 399, in test_process_restart_in_policy_between_vns
assert vn1_fixture.verify_on_setup()
File "/contrail-test/fixtures/vn_test.py", line 581, in verify_on_setup
if not self.verify_vn_in_control_nodes():
File "/contrail-test/tcutils/util.py", line 84, in f_retry
result = f(*args, **kwargs) # first attempt
File "/contrail-test/fixtures/vn_test.py", line 929, in verify_vn_in_control_nodes
vn_name=self.vn_name, project=self.project_name, domain=self.domain_name)
File "/contrail-test/tcutils/control/cn_introspect_utils.py", line 141, in get_cn_config_vn
return self._get_if_map_table_entry(m)
File "/contrail-test/tcutils/control/cn_introspect_utils.py", line 39, in _get_if_map_table_entry
xp = p.xpath('./IFMapTableShowResp/ifmap_db/list/IFMapNodeShowInfo')
AttributeError: 'NoneType' object has no attribute 'xpath'
2025-07-31 05:54:44,891 - DEBUG - Skipping xmpp flap check
2025-07-31 05:54:44,891 - INFO -
2025-07-31 05:54:44,892 - INFO - END TEST : test_process_restart_in_policy_between_vns : FAILED[0:00:04]
2025-07-31 05:54:44,892 - INFO - --------------------------------------------------------------------------------
2025-07-31 05:54:44,902 - INFO - ================================================================================
2025-07-31 05:54:44,902 - INFO - STARTING TEST : test_underlay_broadcast_traffic_handling
2025-07-31 05:54:44,902 - INFO - TEST DESCRIPTION : Test the underlay brocast traffic handling by vrouter. (Bug-1545229).
1. Send broadcast traffic from one compute node.
2. Other compute in same subnet should receive that traffic.
3. Receiving compute should treat this traffic as underlay.
4. Compute should not replicate the packet and send the copy back.
Pass criteria: Step 3-4 should pass
Maintainer : chhandak@juniper.net
2025-07-31 05:54:46,257 - DEBUG - Skipping xmpp flap check
2025-07-31 05:54:46,258 - INFO - Initial checks done. Running the testcase now
2025-07-31 05:54:46,258 - INFO -
2025-07-31 05:54:46,690 - INFO - Agent interface name: ens6
2025-07-31 05:54:46,785 - DEBUG - Executing command: sudo tcpdump -nni ens6 -U host 10.20.0.255 -w /tmp/ens6_ctest-random-18274889.pcap
2025-07-31 05:54:48,294 - ERROR - NameError
Python 3.6.8: /usr/bin/python3
Thu Jul 31 05:54:46 2025
A problem occurred in a Python script. Here is the sequence of
function calls leading up to the error, in the order they occurred.
/contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={})
78 log.info('Initial checks done. Running the testcase now')
79 log.info('')
80 result = function(self, *args, **kwargs)
81 if self.inputs.upgrade:
82 pid = os.getpid()
result = None
function =
self =
args = ()
kwargs = {}
/contrail-test/serial_scripts/vm_regression/test_vm_serial.py in test_underlay_broadcast_traffic_handling(self=)
914 compute_password, comp_intf, filters, self.logger)
915
916 sleep(5)
917
918 # Ping broadcast address
sleep undefined
NameError: name 'sleep' is not defined
__cause__ = None
__class__ =
__context__ = None
__delattr__ =
__dict__ = {}
__dir__ =
__doc__ = 'Name not found globally.'
__eq__ =
__format__ =
__ge__ =
__getattribute__ =
__gt__ =
__hash__ =
__init__ =
__init_subclass__ =
__le__ =
__lt__ =
__ne__ =
__new__ =
__reduce__ =
__reduce_ex__ =
__repr__ =
__setattr__ =
__setstate__ =
__sizeof__ =
__str__ =
__subclasshook__ =
__suppress_context__ = False
__traceback__ =
args = ("name 'sleep' is not defined",)
with_traceback =
The above is a description of an error in a Python program. Here is
the original traceback:
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/vm_regression/test_vm_serial.py", line 916, in test_underlay_broadcast_traffic_handling
sleep(5)
NameError: name 'sleep' is not defined
2025-07-31 05:54:48,294 - DEBUG - Skipping xmpp flap check
2025-07-31 05:54:48,296 - INFO -
2025-07-31 05:54:48,296 - INFO - END TEST : test_underlay_broadcast_traffic_handling : FAILED[0:00:04]
2025-07-31 05:54:48,296 - INFO - --------------------------------------------------------------------------------
2025-07-31 05:54:49,163 - INFO - Deleted project: ctest-TestBasicVMVN0-68332753, ID : ac5f5468-d7a8-4019-8ee1-9a411ff512b3