2025-10-23 21:26:54,831 - INFO - Domain Default found not creating
2025-10-23 21:26:55,019 - INFO - Project ctest-TestBasicVMVN-06750230 not found, creating it
2025-10-23 21:26:55,466 - INFO - Created Project:ctest-TestBasicVMVN-06750230, ID : 2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b 
2025-10-23 21:26:57,150 - INFO - ================================================================================
2025-10-23 21:26:57,150 - INFO - STARTING TEST    : test_generic_link_local_service
2025-10-23 21:26:57,150 - INFO - TEST DESCRIPTION : 
        Description: Test to validate generic linklocal service - running nova list from vm.
            1.Create generic link local service to be able to wget to jenkins
            2.Create a vm
            3.Try wget to jenkins - passes if successful else fails
        Maintainer: sandipd@juniper.net
        
2025-10-23 21:26:57,413 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with
2025-10-23 21:26:57,413 - INFO - Initial checks done. Running the testcase now
2025-10-23 21:26:57,413 - INFO - 
2025-10-23 21:26:58,012 - DEBUG - Response for create_network : {'network': {'id': '2497f84c-435e-4151-9de1-6d8d975e4b74', 'name': 'ctest-vn2_metadata-78395961', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-06750230', 'ctest-vn2_metadata-78395961'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 21:26:58,191 - DEBUG - Response for create_subnet : {'subnet': {'id': '3ba38ebd-1dbe-484d-baca-766cf0dbd6e2', 'name': '', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '2497f84c-435e-4151-9de1-6d8d975e4b74', 'ip_version': 4, 'cidr': '11.1.1.0/24', 'allocation_pools': [{'start': '11.1.1.2', 'end': '11.1.1.254'}], 'gateway_ip': '11.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '11.1.1.2', 'tags': [], 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:26:58,215 - INFO - Created VN ctest-vn2_metadata-78395961
2025-10-23 21:26:58,267 - DEBUG - VN ctest-vn2_metadata-78395961 UUID is 2497f84c-435e-4151-9de1-6d8d975e4b74
2025-10-23 21:26:58,454 - DEBUG - Services list from nova: [, , ]
2025-10-23 21:26:59,582 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4468-1)
2025-10-23 21:26:59,691 - DEBUG - Link local service introspect added
2025-10-23 21:26:59,733 - DEBUG - VM  is still in BUILD state, Expected: ACTIVE
2025-10-23 21:27:04,817 - DEBUG - VM  is in ACTIVE state now
2025-10-23 21:27:04,948 - INFO - Waiting for VM ctest-TestBasicVMVN-06750230-88822844 to be up..
2025-10-23 21:27:04,948 - INFO - VM name : ctest-TestBasicVMVN-06750230-88822844
2025-10-23 21:27:05,027 - DEBUG - VM ctest-TestBasicVMVN-06750230-88822844 ID is 344e7b39-856e-43ef-ae50-19e803457096
2025-10-23 21:27:05,027 - DEBUG - VM ctest-TestBasicVMVN-06750230-88822844 launched on Node cn-jenkins-deploy-platform-ansible-os-4468-1
2025-10-23 21:27:05,105 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/344e7b39-856e-43ef-ae50-19e803457096
2025-10-23 21:27:05,443 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/344e7b39-856e-43ef-ae50-19e803457096
2025-10-23 21:27:05,474 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/1c762a36-178b-4f94-97a3-34070db9032e
2025-10-23 21:27:08,735 - 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-10-23 21:27:08,735 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-88822844 failed!
2025-10-23 21:27:08,792 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn2_metadata-78395961 is 11.1.1.1 and allocation pool is NOT set
2025-10-23 21:27:10,854 - 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=2.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.710 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.710/1.603/2.496/0.893 ms')
2025-10-23 21:27:10,854 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-88822844 passed
2025-10-23 21:27:11,004 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:11,004 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-88822844, IP 11.1.1.3, Port 22
2025-10-23 21:27:11,169 - DEBUG - VM ctest-TestBasicVMVN-06750230-88822844 is ready for SSH connections
2025-10-23 21:27:11,169 - INFO - Retry 0
2025-10-23 21:27:11,169 - DEBUG - Running remote_cmd, Cmd : wget http://169.254.1.2:8083 --spider && echo "Successful", host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:27:11,169 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful"
2025-10-23 21:27:12,882 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083)
Successful
2025-10-23 21:27:12,882 - INFO - Generic metadata worked
2025-10-23 21:27:13,135 - DEBUG - Link local service introspect removed
2025-10-23 21:27:13,135 - INFO - Deleting VM ctest-TestBasicVMVN-06750230-88822844
2025-10-23 21:27:13,211 - INFO - Deleting VN ctest-vn2_metadata-78395961 
2025-10-23 21:27:13,246 - DEBUG - VN 2497f84c-435e-4151-9de1-6d8d975e4b74 still in use: Unable to complete operation on network 2497f84c-435e-4151-9de1-6d8d975e4b74. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2e0364ad-535e-4d08-90d0-0c851e79e03b']
2025-10-23 21:27:13,246 - WARNING - Deleting VN ctest-vn2_metadata-78395961 failed..Will retry
2025-10-23 21:27:15,348 - DEBUG - Response for deleting network ()
2025-10-23 21:27:15,610 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 21:27:15,611 - INFO - END TEST : test_generic_link_local_service : PASSED[0:00:18]
2025-10-23 21:27:15,611 - INFO - --------------------------------------------------------------------------------
2025-10-23 21:27:15,613 - INFO - ================================================================================
2025-10-23 21:27:15,613 - INFO - STARTING TEST    : test_metadata_service
2025-10-23 21:27:15,613 - INFO - TEST DESCRIPTION : 
        Description: Test to validate metadata service on VM creation.
               1.Verify from global-vrouter-config if metadata configures or not - fails otherwise
               2.Create a shell script which writes  'hello world ' in a file in /tmp and save the script on the nova api node
               3.Create a vm with userdata pointing to that script - script should get executed during vm boot up
               4.Go to the vm and verify if the file with 'hello world ' written saved in /tmp of the vm - fails otherwise
            Maintainer: sandipd@juniper.net
        
2025-10-23 21:27:15,896 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with
2025-10-23 21:27:15,896 - INFO - Initial checks done. Running the testcase now
2025-10-23 21:27:15,897 - INFO - 
2025-10-23 21:27:15,897 - DEBUG - Requesting: http://10.0.0.254:8082/global-vrouter-configs
2025-10-23 21:27:15,903 - DEBUG - Requesting: http://10.0.0.254:8082/global-vrouter-config/0642a6ab-a6e0-44e1-8bea-164599e7c8a2
2025-10-23 21:27:16,085 - DEBUG - Response for create_network : {'network': {'id': '9b733dd9-e56c-4a4c-b8ac-9b41f63a0e28', 'name': 'ctest-vn-33457526', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-06750230', 'ctest-vn-33457526'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 21:27:16,277 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f1aafd3f-1d1d-4545-bfe3-a431216f4352', 'name': '', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '9b733dd9-e56c-4a4c-b8ac-9b41f63a0e28', 'ip_version': 4, 'cidr': '141.26.216.64/26', 'allocation_pools': [{'start': '141.26.216.66', 'end': '141.26.216.126'}], 'gateway_ip': '141.26.216.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.26.216.66', 'tags': [], 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:27:16,295 - INFO - Created VN ctest-vn-33457526
2025-10-23 21:27:16,347 - DEBUG - VN ctest-vn-33457526 UUID is 9b733dd9-e56c-4a4c-b8ac-9b41f63a0e28
2025-10-23 21:27:17,615 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4468-1)
2025-10-23 21:27:17,615 - INFO - Waiting for VM ctest-TestBasicVMVN-06750230-26604759 to be up..
2025-10-23 21:27:17,662 - DEBUG - VM  is still in BUILD state, Expected: ACTIVE
2025-10-23 21:27:22,738 - DEBUG - VM  is in ACTIVE state now
2025-10-23 21:27:22,738 - INFO - VM name : ctest-TestBasicVMVN-06750230-26604759
2025-10-23 21:27:22,810 - DEBUG - VM ctest-TestBasicVMVN-06750230-26604759 ID is 6913a679-84f6-4a70-8cf0-e12cbedd29db
2025-10-23 21:27:22,810 - DEBUG - VM ctest-TestBasicVMVN-06750230-26604759 launched on Node cn-jenkins-deploy-platform-ansible-os-4468-1
2025-10-23 21:27:22,886 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/6913a679-84f6-4a70-8cf0-e12cbedd29db
2025-10-23 21:27:22,895 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/f98f06f6-ae12-4d9f-90d6-48e3bf3b9976
2025-10-23 21:27:26,146 - 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-10-23 21:27:26,146 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-26604759 failed!
2025-10-23 21:27:26,199 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-33457526 is 141.26.216.65 and allocation pool is NOT set
2025-10-23 21:27:28,268 - 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=14.1 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.02 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.015/8.034/14.054/6.019 ms')
2025-10-23 21:27:28,268 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-26604759 passed
2025-10-23 21:27:28,416 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:28,416 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-26604759, IP 141.26.216.67, Port 22
2025-10-23 21:27:28,485 - DEBUG - Error on ssh to cirros@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-10-23 21:27:28,567 - DEBUG - VM ctest-TestBasicVMVN-06750230-26604759 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-23 21:27:33,567 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:33,567 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-26604759, IP 141.26.216.67, Port 22
2025-10-23 21:27:33,724 - DEBUG - VM ctest-TestBasicVMVN-06750230-26604759 is ready for SSH connections
2025-10-23 21:27:33,724 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:27:33,724 - DEBUG - cat /tmp/output.txt
2025-10-23 21:27:35,403 - DEBUG - Hello World.  The time is now Thu, 23 Oct 2025 15:27:30 -0600!
2025-10-23 21:27:35,403 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World.  The time is now Thu, 23 Oct 2025 15:27:30 -0600!'}
2025-10-23 21:27:35,403 - INFO - metadata_script.txt got executed in the vm
2025-10-23 21:27:35,403 - INFO - Deleting VM ctest-TestBasicVMVN-06750230-26604759
2025-10-23 21:27:35,483 - INFO - Deleting VN ctest-vn-33457526 
2025-10-23 21:27:35,518 - DEBUG - VN 9b733dd9-e56c-4a4c-b8ac-9b41f63a0e28 still in use: Unable to complete operation on network 9b733dd9-e56c-4a4c-b8ac-9b41f63a0e28. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4eabe3c5-b5a7-43cb-883d-7c1f3add025c']
2025-10-23 21:27:35,518 - WARNING - Deleting VN ctest-vn-33457526 failed..Will retry
2025-10-23 21:27:37,632 - DEBUG - Response for deleting network ()
2025-10-23 21:27:37,888 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 21:27:37,888 - INFO - END TEST : test_metadata_service : PASSED[0:00:22]
2025-10-23 21:27:37,888 - INFO - --------------------------------------------------------------------------------
2025-10-23 21:27:37,891 - INFO - ================================================================================
2025-10-23 21:27:37,891 - INFO - STARTING TEST    : test_ping_within_vn_two_vms_two_different_subnets
2025-10-23 21:27:37,891 - INFO - TEST DESCRIPTION : 
        Description:  Validate Ping between 2 VMs in the same VN, 2 VMs in different VN
        subnets.
        Test steps:
                1. Create 1 IPAM's.
                2. Create 1 VN with 2 subnets and launch 2 VMs in them.
                3. Ping between the VMs in the same VN should go thru fine.
                4. Ping to the subnet broadcast and all-broadcast address.
        Pass criteria: VM in the same subnet will respond to both the pings, while the VM in a different VN should respond only to the
                        all-broadcast address.
        Maintainer : ganeshahv@juniper.net
        
2025-10-23 21:27:38,182 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with
2025-10-23 21:27:38,182 - INFO - Initial checks done. Running the testcase now
2025-10-23 21:27:38,182 - INFO - 
2025-10-23 21:27:38,674 - DEBUG - Response for create_network : {'network': {'id': '3ee5b084-ba9c-4b1c-849a-ddad76450503', 'name': 'ctest-vn-91973957', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-06750230', 'ctest-vn-91973957'], 'port_security_enabled': True, 'description': ''}}
2025-10-23 21:27:38,855 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd9db7cd6-526e-446b-b0d4-a17b027a96e0', 'name': '', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '3ee5b084-ba9c-4b1c-849a-ddad76450503', 'ip_version': 4, 'cidr': '31.1.1.0/29', 'allocation_pools': [{'start': '31.1.1.2', 'end': '31.1.1.6'}], 'gateway_ip': '31.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.1.2', 'tags': [], 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:27:39,063 - DEBUG - Response for create_subnet : {'subnet': {'id': '4a37eb4a-e669-4e17-ae43-2bee98564a9f', 'name': '', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '3ee5b084-ba9c-4b1c-849a-ddad76450503', 'ip_version': 4, 'cidr': '31.1.2.0/29', 'allocation_pools': [{'start': '31.1.2.2', 'end': '31.1.2.6'}], 'gateway_ip': '31.1.2.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.1.2.2', 'tags': [], 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:27:39,082 - INFO - Created VN ctest-vn-91973957
2025-10-23 21:27:39,135 - DEBUG - VN ctest-vn-91973957 UUID is 3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:39,493 - DEBUG - Response for create_port : {'port': {'name': '35f15cb3-dea9-406b-94b3-02fbd9470d7d', 'id': '35f15cb3-dea9-406b-94b3-02fbd9470d7d', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '3ee5b084-ba9c-4b1c-849a-ddad76450503', 'mac_address': '02:35:f1:5c:b3:de', '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': '31.1.1.4', 'subnet_id': 'd9db7cd6-526e-446b-b0d4-a17b027a96e0'}], 'security_groups': ['8886ecaf-8a68-490c-8658-5087fb0b609a'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:27:39,659 - DEBUG - Response for create_port : {'port': {'name': '0263b26a-5317-417f-87da-8cedf31a82b6', 'id': '0263b26a-5317-417f-87da-8cedf31a82b6', 'tenant_id': '2c6bf9f48ada4939ada92dfce19f5a9b', 'network_id': '3ee5b084-ba9c-4b1c-849a-ddad76450503', 'mac_address': '02:02:63:b2:6a:53', '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': '31.1.2.4', 'subnet_id': '4a37eb4a-e669-4e17-ae43-2bee98564a9f'}], 'security_groups': ['8886ecaf-8a68-490c-8658-5087fb0b609a'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': '2c6bf9f48ada4939ada92dfce19f5a9b'}}
2025-10-23 21:27:40,045 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4468-1)
2025-10-23 21:27:40,483 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4468-1)
2025-10-23 21:27:41,817 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4468-1)
2025-10-23 21:27:41,818 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-23 21:27:41,824 - DEBUG - Requesting: http://10.0.0.254:8082/domain/b2209111-6f48-42a4-aade-8a3132a84b97
2025-10-23 21:27:41,839 - DEBUG - Requesting: http://10.0.0.254:8082/project/2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b
2025-10-23 21:27:41,930 - DEBUG - Requesting: http://10.0.0.254:8082/network-ipam/cdcf1a65-1aff-4d54-b741-21d6999f7022
2025-10-23 21:27:41,945 - INFO - Verifications in API Server for IPAM: ctest-ipam-04829218 passed
2025-10-23 21:27:41,952 - DEBUG - Control-node 10.0.0.254 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-06750230:ctest-ipam-04829218', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-06750230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14830101119501290836', 'uuid-lslong': '13204872788011085858'}, 'enable': 'true', 'created': '2025-10-23T21:27:38', 'last-modified': '2025-10-23T21:27:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.543911'}
2025-10-23 21:27:41,952 - INFO - Verifications in Control node for IPAM: ctest-ipam-04829218 passed
2025-10-23 21:27:41,952 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-23 21:27:41,959 - DEBUG - Requesting: http://10.0.0.254:8082/domain/b2209111-6f48-42a4-aade-8a3132a84b97
2025-10-23 21:27:41,970 - DEBUG - Requesting: http://10.0.0.254:8082/project/2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b
2025-10-23 21:27:42,057 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,072 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,083 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:27:42,097 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:27:42,110 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/628d033c-032e-43f5-aa01-332fa608b03d
2025-10-23 21:27:42,121 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 21:27:42,122 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,137 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:27:42,161 - INFO - Verified VN network id 11 for VN 3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,161 - INFO - Verifications in API Server for VN ctest-vn-91973957 passed
2025-10-23 21:27:42,161 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,176 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:27:42,186 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:27:42,195 - DEBUG - Requesting: http://10.0.0.254:8082/route-target/628d033c-032e-43f5-aa01-332fa608b03d
2025-10-23 21:27:42,216 - DEBUG - Control-node 10.0.0.254 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-06750230', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4532222684131248924', 'uuid-lslong': '9555193296503244035'}, 'enable': 'true', 'created': '2025-10-23T21:27:38', 'last-modified': '2025-10-23T21:27:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.173867'}
2025-10-23 21:27:42,225 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-23 21:27:42,225 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-91973957 passed
2025-10-23 21:27:42,225 - DEBUG - ====Verifying policy data for ctest-vn-91973957 in API_Server ======
2025-10-23 21:27:42,226 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-23 21:27:42,234 - DEBUG - Requesting: http://10.0.0.254:8082/domain/b2209111-6f48-42a4-aade-8a3132a84b97
2025-10-23 21:27:42,251 - DEBUG - Requesting: http://10.0.0.254:8082/project/2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b
2025-10-23 21:27:42,345 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-network/3ee5b084-ba9c-4b1c-849a-ddad76450503
2025-10-23 21:27:42,364 - DEBUG - =>VN ctest-vn-91973957 has no policy to be verified
2025-10-23 21:27:42,364 - DEBUG - Verifying the vn in opserver
2025-10-23 21:27:42,364 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 virtual network link  through opserver 10.0.0.254
2025-10-23 21:27:42,364 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-23 21:27:42,802 - DEBUG - Requesting: http://10.0.0.254:8081/analytics/uves/virtual-networks
2025-10-23 21:27:42,866 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957', 'href': 'http://10.0.0.254:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957?flat'}
2025-10-23 21:27:42,866 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 is found in opserver
2025-10-23 21:27:42,879 - DEBUG - VRF ids for VN ctest-vn-91973957: {'10.0.0.254': '2'}
2025-10-23 21:27:42,879 - INFO - Waiting for VM ctest-TestBasicVMVN-06750230-27911131 to be up..
2025-10-23 21:27:42,979 - DEBUG - VM  is in ACTIVE state now
2025-10-23 21:27:42,979 - INFO - VM name : ctest-TestBasicVMVN-06750230-27911131
2025-10-23 21:27:43,069 - DEBUG - VM ctest-TestBasicVMVN-06750230-27911131 ID is dd02c0ec-617d-498c-bc36-d529d58aeb68
2025-10-23 21:27:43,069 - DEBUG - VM ctest-TestBasicVMVN-06750230-27911131 launched on Node cn-jenkins-deploy-platform-ansible-os-4468-1
2025-10-23 21:27:43,155 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/dd02c0ec-617d-498c-bc36-d529d58aeb68
2025-10-23 21:27:43,164 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/35f15cb3-dea9-406b-94b3-02fbd9470d7d
2025-10-23 21:27:46,436 - 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-10-23 21:27:46,436 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-27911131 failed!
2025-10-23 21:27:46,491 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 is 31.1.1.1 and allocation pool is NOT set
2025-10-23 21:27:46,492 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 is 31.1.2.1 and allocation pool is NOT set
2025-10-23 21:27:50,563 - 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-10-23 21:27:50,563 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-27911131 failed!
2025-10-23 21:27:50,623 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 is 31.1.1.1 and allocation pool is NOT set
2025-10-23 21:27:50,624 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957 is 31.1.2.1 and allocation pool is NOT set
2025-10-23 21:27:52,683 - 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=6.19 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.626 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.626/3.409/6.193/2.783 ms')
2025-10-23 21:27:52,683 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-06750230-27911131 passed
2025-10-23 21:27:52,832 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:52,832 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-27911131, IP 31.1.1.4, Port 22
2025-10-23 21:27:52,988 - DEBUG - VM ctest-TestBasicVMVN-06750230-27911131 is ready for SSH connections
2025-10-23 21:27:52,988 - INFO - Waiting for VM ctest-TestBasicVMVN-06750230-50998920 to be up..
2025-10-23 21:27:53,082 - DEBUG - VM  is in ACTIVE state now
2025-10-23 21:27:53,082 - INFO - VM name : ctest-TestBasicVMVN-06750230-50998920
2025-10-23 21:27:53,157 - DEBUG - VM ctest-TestBasicVMVN-06750230-50998920 ID is e050c9b2-5ecc-4545-bc2d-ec19b0d9a2f9
2025-10-23 21:27:53,157 - DEBUG - VM ctest-TestBasicVMVN-06750230-50998920 launched on Node cn-jenkins-deploy-platform-ansible-os-4468-1
2025-10-23 21:27:53,230 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/e050c9b2-5ecc-4545-bc2d-ec19b0d9a2f9
2025-10-23 21:27:53,238 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/0263b26a-5317-417f-87da-8cedf31a82b6
2025-10-23 21:27:54,484 - 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=5.89 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.94 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 = 2.943/4.416/5.889/1.473 ms')
2025-10-23 21:27:54,484 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-06750230-50998920 passed
2025-10-23 21:27:54,643 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:54,644 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-50998920, IP 31.1.2.4, Port 22
2025-10-23 21:27:54,799 - DEBUG - VM ctest-TestBasicVMVN-06750230-50998920 is ready for SSH connections
2025-10-23 21:27:54,799 - INFO - Waiting for VM ctest-TestBasicVMVN-06750230-48744219 to be up..
2025-10-23 21:27:54,872 - DEBUG - VM  is in ACTIVE state now
2025-10-23 21:27:54,872 - INFO - VM name : ctest-TestBasicVMVN-06750230-48744219
2025-10-23 21:27:54,948 - DEBUG - VM ctest-TestBasicVMVN-06750230-48744219 ID is a6d01013-859b-4af8-8d64-2cdd3b6d7112
2025-10-23 21:27:54,948 - DEBUG - VM ctest-TestBasicVMVN-06750230-48744219 launched on Node cn-jenkins-deploy-platform-ansible-os-4468-1
2025-10-23 21:27:55,041 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/a6d01013-859b-4af8-8d64-2cdd3b6d7112
2025-10-23 21:27:55,051 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/1384fbee-acee-4920-8db7-9b2eee859a0b
2025-10-23 21:27:56,291 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=2.89 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.532 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.532/1.713/2.894/1.181 ms')
2025-10-23 21:27:56,291 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-06750230-48744219 passed
2025-10-23 21:27:56,440 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:56,440 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-06750230-48744219, IP 31.1.1.3, Port 22
2025-10-23 21:27:56,595 - DEBUG - VM ctest-TestBasicVMVN-06750230-48744219 is ready for SSH connections
2025-10-23 21:27:56,595 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:27:56,595 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  31.1.2.4, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:27:56,596 - DEBUG - ping -s 56 -c 3 -W 1  31.1.2.4
2025-10-23 21:28:00,678 - DEBUG - PING 31.1.2.4 (31.1.2.4): 56 data bytes
64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.055 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=0.944 ms
64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.084 ms
--- 31.1.2.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.944/2.361/5.055 ms
2025-10-23 21:28:00,678 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-06750230-27911131 passed
2025-10-23 21:28:00,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:28:00,678 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  31.1.1.4, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:00,678 - DEBUG - ping -s 56 -c 3 -W 1  31.1.1.4
2025-10-23 21:28:04,392 - DEBUG - PING 31.1.1.4 (31.1.1.4): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=63 time=3.247 ms
64 bytes from 31.1.1.4: seq=1 ttl=63 time=0.965 ms
64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.090 ms
--- 31.1.1.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.965/1.767/3.247 ms
2025-10-23 21:28:04,392 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-06750230-50998920 passed
2025-10-23 21:28:04,392 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:28:04,392 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:28:04,392 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 21:28:04,392 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:04,392 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-10-23 21:28:04,587 - DEBUG - None
2025-10-23 21:28:04,588 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:04,588 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-10-23 21:28:04,783 - DEBUG - None
2025-10-23 21:28:04,783 - DEBUG - Running remote_cmd, Cmd : echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:04,783 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-10-23 21:28:05,473 - DEBUG - None
2025-10-23 21:28:05,473 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  31.1.1.7, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:05,473 - DEBUG - ping -s 56 -c 3 -W 1  31.1.1.7
2025-10-23 21:28:07,703 - DEBUG - PING 31.1.1.7 (31.1.1.7): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=2.502 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.540 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.658 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.527 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.409 ms
--- 31.1.1.7 ping statistics ---
3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss
round-trip min/avg/max = 0.409/2.527/6.540 ms
2025-10-23 21:28:07,704 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  224.0.0.1, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:07,704 - DEBUG - ping -s 56 -c 3 -W 1  224.0.0.1
2025-10-23 21:28:09,965 - DEBUG - PING 224.0.0.1 (224.0.0.1): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=1.228 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=3.358 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=4.614 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.701 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.386 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.358 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.432 ms
--- 224.0.0.1 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.432/2.296/4.614 ms
2025-10-23 21:28:09,965 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1  255.255.255.255, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123
2025-10-23 21:28:09,966 - DEBUG - ping -s 56 -c 3 -W 1  255.255.255.255
2025-10-23 21:28:12,231 - DEBUG - PING 255.255.255.255 (255.255.255.255): 56 data bytes
64 bytes from 31.1.1.4: seq=0 ttl=64 time=0.751 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=2.955 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=4.037 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.497 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.879 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.940 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.412 ms
--- 255.255.255.255 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.412/1.924/4.037 ms
2025-10-23 21:28:12,231 - INFO - Deleting VM ctest-TestBasicVMVN-06750230-48744219
2025-10-23 21:28:12,300 - INFO - Detaching port 0263b26a-5317-417f-87da-8cedf31a82b6 from VM ctest-TestBasicVMVN-06750230-50998920
2025-10-23 21:28:12,336 - INFO - Deleting VM ctest-TestBasicVMVN-06750230-50998920
2025-10-23 21:28:12,409 - INFO - Detaching port 35f15cb3-dea9-406b-94b3-02fbd9470d7d from VM ctest-TestBasicVMVN-06750230-27911131
2025-10-23 21:28:12,444 - INFO - Deleting VM ctest-TestBasicVMVN-06750230-27911131
2025-10-23 21:28:12,526 - INFO - Deleting VN ctest-vn-91973957 
2025-10-23 21:28:12,696 - DEBUG - Response for delete_port : ()
2025-10-23 21:28:12,888 - DEBUG - Response for delete_port : ()
2025-10-23 21:28:12,951 - DEBUG - VN 3ee5b084-ba9c-4b1c-849a-ddad76450503 still in use: Unable to complete operation on network 3ee5b084-ba9c-4b1c-849a-ddad76450503. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c9a7c2ff-764a-480f-9a89-0fb252e421fb']
2025-10-23 21:28:12,951 - WARNING - Deleting VN ctest-vn-91973957 failed..Will retry
2025-10-23 21:28:15,078 - DEBUG - Response for deleting network ()
2025-10-23 21:28:15,078 - DEBUG - Requesting: http://10.0.0.254:8082/routing-instance/e2380f76-f653-4687-9040-dc6333d10cb7
2025-10-23 21:28:15,084 - DEBUG - Response Code: 404
2025-10-23 21:28:15,084 - DEBUG - Requesting: http://10.0.0.254:8082/domains
2025-10-23 21:28:15,090 - DEBUG - Requesting: http://10.0.0.254:8082/domain/b2209111-6f48-42a4-aade-8a3132a84b97
2025-10-23 21:28:15,149 - DEBUG - Requesting: http://10.0.0.254:8082/project/2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b
2025-10-23 21:28:15,227 - INFO - Validated that VN ctest-vn-91973957 is not found in API Server
2025-10-23 21:28:15,237 - DEBUG - VN ctest-vn-91973957 is not present in Agent 10.0.0.254 
2025-10-23 21:28:15,237 - INFO - Validated that VN ctest-vn-91973957 is not in any agent
2025-10-23 21:28:15,241 - DEBUG - VRF 2 is not seen in agent 10.0.0.254
2025-10-23 21:28:15,247 - DEBUG - Vrouter 10.0.0.254 does not have vrf 2 for VN ctest-vn-91973957
2025-10-23 21:28:15,247 - INFO - Validated that all vrouters do not  have the route table for VN default-domain:ctest-TestBasicVMVN-06750230:ctest-vn-91973957
2025-10-23 21:28:15,256 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-91973957 info
2025-10-23 21:28:15,327 - INFO - IPAM: ctest-ipam-04829218 is not found in API Server
2025-10-23 21:28:15,337 - INFO - IPAM:ctest-ipam-04829218 is not found in control node
2025-10-23 21:28:15,593 - DEBUG - No XMPP flaps were noticed during the test
2025-10-23 21:28:15,593 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:38]
2025-10-23 21:28:15,593 - INFO - --------------------------------------------------------------------------------
2025-10-23 21:28:16,391 - INFO - Deleted project: ctest-TestBasicVMVN-06750230, ID : 2c6bf9f4-8ada-4939-ada9-2dfce19f5a9b