2025-04-09 16:33:28,520 - INFO - Domain Default found not creating
2025-04-09 16:33:28,714 - INFO - Project ctest-TestBasicVMVN-51285041 not found, creating it
2025-04-09 16:33:29,274 - INFO - Created Project:ctest-TestBasicVMVN-51285041, ID : f9336da9-6a09-4b19-903d-33969d540d54
2025-04-09 16:33:30,894 - INFO - ================================================================================
2025-04-09 16:33:30,894 - INFO - STARTING TEST : test_generic_link_local_service
2025-04-09 16:33:30,894 - 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-04-09 16:33:31,175 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.254': '0'}} with
2025-04-09 16:33:31,175 - INFO - Initial checks done. Running the testcase now
2025-04-09 16:33:31,175 - INFO -
2025-04-09 16:33:31,765 - DEBUG - Response for create_network : {'network': {'id': 'a6d12c6d-be24-41f3-b6d6-89abfb0543e6', 'name': 'ctest-vn2_metadata-23779472', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'project_id': 'f9336da96a094b19903d33969d540d54', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-51285041', 'ctest-vn2_metadata-23779472'], 'port_security_enabled': True, 'description': ''}}
2025-04-09 16:33:31,969 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c97dd45f-5ca2-4de9-b532-73b2610f46da', 'name': '', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': 'a6d12c6d-be24-41f3-b6d6-89abfb0543e6', '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': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:33:31,995 - INFO - Created VN ctest-vn2_metadata-23779472
2025-04-09 16:33:32,053 - DEBUG - VN ctest-vn2_metadata-23779472 UUID is a6d12c6d-be24-41f3-b6d6-89abfb0543e6
2025-04-09 16:33:32,287 - DEBUG - Services list from nova: [, , ]
2025-04-09 16:33:33,534 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3071-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3071-1)
2025-04-09 16:33:33,644 - DEBUG - Link local service introspect added
2025-04-09 16:33:33,692 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-04-09 16:33:38,794 - DEBUG - VM is in ACTIVE state now
2025-04-09 16:33:38,957 - INFO - Waiting for VM ctest-TestBasicVMVN-51285041-06742650 to be up..
2025-04-09 16:33:38,957 - INFO - VM name : ctest-TestBasicVMVN-51285041-06742650
2025-04-09 16:33:39,082 - DEBUG - VM ctest-TestBasicVMVN-51285041-06742650 ID is cea917f9-9f3f-4319-a050-e31d7a3cc2a1
2025-04-09 16:33:39,082 - DEBUG - VM ctest-TestBasicVMVN-51285041-06742650 launched on Node cn-jenkins-deploy-platform-ansible-os-3071-1
2025-04-09 16:33:39,182 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/cea917f9-9f3f-4319-a050-e31d7a3cc2a1
2025-04-09 16:33:39,508 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/cea917f9-9f3f-4319-a050-e31d7a3cc2a1
2025-04-09 16:33:39,546 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/82aa0a52-849f-4cbc-81a0-3ef7414296f3
2025-04-09 16:33:42,840 - 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-04-09 16:33:42,840 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-06742650 failed!
2025-04-09 16:33:42,901 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn2_metadata-23779472 is 11.1.1.1 and allocation pool is NOT set
2025-04-09 16:33:44,967 - 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=9.37 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.64 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.644/6.008/9.372/3.364 ms')
2025-04-09 16:33:44,968 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-06742650 passed
2025-04-09 16:33:45,129 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:33:45,129 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-06742650, IP 11.1.1.3, Port 22
2025-04-09 16:33:45,307 - DEBUG - VM ctest-TestBasicVMVN-51285041-06742650 is ready for SSH connections
2025-04-09 16:33:45,307 - INFO - Retry 0
2025-04-09 16:33:45,308 - 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.48, gateway password: c0ntrail123
2025-04-09 16:33:45,308 - DEBUG - wget http://169.254.1.2:8083 --spider && echo "Successful"
2025-04-09 16:33:46,998 - DEBUG - Connecting to 169.254.1.2:8083 (169.254.1.2:8083)
Successful
2025-04-09 16:33:46,998 - INFO - Generic metadata worked
2025-04-09 16:33:47,272 - DEBUG - Link local service introspect removed
2025-04-09 16:33:47,273 - INFO - Deleting VM ctest-TestBasicVMVN-51285041-06742650
2025-04-09 16:33:47,349 - INFO - Deleting VN ctest-vn2_metadata-23779472
2025-04-09 16:33:47,391 - DEBUG - VN a6d12c6d-be24-41f3-b6d6-89abfb0543e6 still in use: Unable to complete operation on network a6d12c6d-be24-41f3-b6d6-89abfb0543e6. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a5cb41ec-9036-495e-a3e5-045ab543f29e']
2025-04-09 16:33:47,391 - WARNING - Deleting VN ctest-vn2_metadata-23779472 failed..Will retry
2025-04-09 16:33:49,511 - DEBUG - Response for deleting network ()
2025-04-09 16:33:49,772 - DEBUG - No XMPP flaps were noticed during the test
2025-04-09 16:33:49,772 - INFO - END TEST : test_generic_link_local_service : PASSED[0:00:19]
2025-04-09 16:33:49,773 - INFO - --------------------------------------------------------------------------------
2025-04-09 16:33:49,777 - INFO - ================================================================================
2025-04-09 16:33:49,777 - INFO - STARTING TEST : test_metadata_service
2025-04-09 16:33:49,777 - 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-04-09 16:33:50,068 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.254': '0'}} with
2025-04-09 16:33:50,068 - INFO - Initial checks done. Running the testcase now
2025-04-09 16:33:50,069 - INFO -
2025-04-09 16:33:50,069 - DEBUG - Requesting: http://10.0.0.48:8082/global-vrouter-configs
2025-04-09 16:33:50,078 - DEBUG - Requesting: http://10.0.0.48:8082/global-vrouter-config/7e62ee32-cef7-4ced-b9ac-446036b59173
2025-04-09 16:33:50,293 - DEBUG - Response for create_network : {'network': {'id': 'c1dbbdd5-db63-4e45-bc8c-4b5b5ec756bc', 'name': 'ctest-vn-04555363', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'project_id': 'f9336da96a094b19903d33969d540d54', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-51285041', 'ctest-vn-04555363'], 'port_security_enabled': True, 'description': ''}}
2025-04-09 16:33:50,523 - DEBUG - Response for create_subnet : {'subnet': {'id': '45c201b2-5259-4864-a2da-f56828b2bc61', 'name': '', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': 'c1dbbdd5-db63-4e45-bc8c-4b5b5ec756bc', 'ip_version': 4, 'cidr': '185.72.215.192/26', 'allocation_pools': [{'start': '185.72.215.194', 'end': '185.72.215.254'}], 'gateway_ip': '185.72.215.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '185.72.215.194', 'tags': [], 'project_id': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:33:50,548 - INFO - Created VN ctest-vn-04555363
2025-04-09 16:33:50,605 - DEBUG - VN ctest-vn-04555363 UUID is c1dbbdd5-db63-4e45-bc8c-4b5b5ec756bc
2025-04-09 16:33:51,840 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3071-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3071-1)
2025-04-09 16:33:51,840 - INFO - Waiting for VM ctest-TestBasicVMVN-51285041-62354168 to be up..
2025-04-09 16:33:51,890 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-04-09 16:33:57,024 - DEBUG - VM is in ACTIVE state now
2025-04-09 16:33:57,024 - INFO - VM name : ctest-TestBasicVMVN-51285041-62354168
2025-04-09 16:33:57,154 - DEBUG - VM ctest-TestBasicVMVN-51285041-62354168 ID is 30e4051c-0cdf-4243-a4c2-86fa35012580
2025-04-09 16:33:57,154 - DEBUG - VM ctest-TestBasicVMVN-51285041-62354168 launched on Node cn-jenkins-deploy-platform-ansible-os-3071-1
2025-04-09 16:33:57,251 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/30e4051c-0cdf-4243-a4c2-86fa35012580
2025-04-09 16:33:57,262 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/a9f7b75f-8ae5-437a-8c54-6a2f5ee1c9e7
2025-04-09 16:34:00,535 - 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 1025ms')
2025-04-09 16:34:00,535 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-62354168 failed!
2025-04-09 16:34:00,601 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-04555363 is 185.72.215.193 and allocation pool is NOT set
2025-04-09 16:34:04,695 - 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=2 ttl=63 time=9.39 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1032ms\r\nrtt min/avg/max/mdev = 9.388/9.388/9.388/0.000 ms')
2025-04-09 16:34:04,695 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-62354168 passed
2025-04-09 16:34:04,853 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:04,853 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-62354168, IP 185.72.215.195, Port 22
2025-04-09 16:34:05,022 - DEBUG - VM ctest-TestBasicVMVN-51285041-62354168 is ready for SSH connections
2025-04-09 16:34:05,022 - DEBUG - Running remote_cmd, Cmd : cat /tmp/output.txt, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-04-09 16:34:05,023 - DEBUG - cat /tmp/output.txt
2025-04-09 16:34:06,708 - DEBUG - Hello World. The time is now Wed, 09 Apr 2025 10:34:04 -0600!
2025-04-09 16:34:06,709 - DEBUG - ret : {'cat /tmp/output.txt': 'Hello World. The time is now Wed, 09 Apr 2025 10:34:04 -0600!'}
2025-04-09 16:34:06,709 - INFO - metadata_script.txt got executed in the vm
2025-04-09 16:34:06,709 - INFO - Deleting VM ctest-TestBasicVMVN-51285041-62354168
2025-04-09 16:34:06,798 - INFO - Deleting VN ctest-vn-04555363
2025-04-09 16:34:06,858 - DEBUG - VN c1dbbdd5-db63-4e45-bc8c-4b5b5ec756bc still in use: Unable to complete operation on network c1dbbdd5-db63-4e45-bc8c-4b5b5ec756bc. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dbca4a4e-e522-4565-a7f7-1630320bfaee']
2025-04-09 16:34:06,858 - WARNING - Deleting VN ctest-vn-04555363 failed..Will retry
2025-04-09 16:34:08,983 - DEBUG - Response for deleting network ()
2025-04-09 16:34:09,245 - DEBUG - No XMPP flaps were noticed during the test
2025-04-09 16:34:09,245 - INFO - END TEST : test_metadata_service : PASSED[0:00:20]
2025-04-09 16:34:09,245 - INFO - --------------------------------------------------------------------------------
2025-04-09 16:34:09,250 - INFO - ================================================================================
2025-04-09 16:34:09,250 - INFO - STARTING TEST : test_ping_within_vn_two_vms_two_different_subnets
2025-04-09 16:34:09,250 - 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-04-09 16:34:09,540 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.254': '0'}} with
2025-04-09 16:34:09,540 - INFO - Initial checks done. Running the testcase now
2025-04-09 16:34:09,540 - INFO -
2025-04-09 16:34:10,160 - DEBUG - Response for create_network : {'network': {'id': '077eb934-09ee-40de-bbff-ea04f2554a84', 'name': 'ctest-vn-34133273', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'project_id': 'f9336da96a094b19903d33969d540d54', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBasicVMVN-51285041', 'ctest-vn-34133273'], 'port_security_enabled': True, 'description': ''}}
2025-04-09 16:34:10,475 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e9207daa-bd3b-4c81-9804-cfc19037db7f', 'name': '', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': '077eb934-09ee-40de-bbff-ea04f2554a84', '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': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:34:10,662 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd474811f-cb0b-4ca8-80e6-439ac157d9f1', 'name': '', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': '077eb934-09ee-40de-bbff-ea04f2554a84', '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': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:34:10,688 - INFO - Created VN ctest-vn-34133273
2025-04-09 16:34:10,745 - DEBUG - VN ctest-vn-34133273 UUID is 077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:11,201 - DEBUG - Response for create_port : {'port': {'name': '1c744d52-b33a-4a99-938c-b9eaadb9d578', 'id': '1c744d52-b33a-4a99-938c-b9eaadb9d578', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': '077eb934-09ee-40de-bbff-ea04f2554a84', 'mac_address': '02:1c:74:4d:52:b3', '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': 'e9207daa-bd3b-4c81-9804-cfc19037db7f'}], 'security_groups': ['1f83e83e-d093-4f29-9bb7-3e79c4b0a09a'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:34:11,410 - DEBUG - Response for create_port : {'port': {'name': 'b877915c-3d4c-4492-95a3-bf9d2d001161', 'id': 'b877915c-3d4c-4492-95a3-bf9d2d001161', 'tenant_id': 'f9336da96a094b19903d33969d540d54', 'network_id': '077eb934-09ee-40de-bbff-ea04f2554a84', 'mac_address': '02:b8:77:91:5c:3d', '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': 'd474811f-cb0b-4ca8-80e6-439ac157d9f1'}], 'security_groups': ['1f83e83e-d093-4f29-9bb7-3e79c4b0a09a'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'port_security_enabled': True, 'description': '', 'project_id': 'f9336da96a094b19903d33969d540d54'}}
2025-04-09 16:34:11,843 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3071-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3071-1)
2025-04-09 16:34:12,300 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3071-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3071-1)
2025-04-09 16:34:13,591 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3071-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3071-1)
2025-04-09 16:34:13,592 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-04-09 16:34:13,607 - DEBUG - Requesting: http://10.0.0.48:8082/domain/7668f027-eb57-47cc-91d2-f0ace78159ef
2025-04-09 16:34:13,632 - DEBUG - Requesting: http://10.0.0.48:8082/project/f9336da9-6a09-4b19-903d-33969d540d54
2025-04-09 16:34:13,717 - DEBUG - Requesting: http://10.0.0.48:8082/network-ipam/40470526-1a33-4e6f-aba5-2d9ed1a741a0
2025-04-09 16:34:13,740 - INFO - Verifications in API Server for IPAM: ctest-ipam-93035228 passed
2025-04-09 16:34:13,754 - DEBUG - Control-node 10.0.0.48 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestBasicVMVN-51285041:ctest-ipam-93035228', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-51285041', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4631676402980310639', 'uuid-lslong': '12368342111788417440'}, 'enable': 'true', 'created': '2025-04-09T16:34:09', 'last-modified': '2025-04-09T16:34:09', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.954918'}
2025-04-09 16:34:13,754 - INFO - Verifications in Control node for IPAM: ctest-ipam-93035228 passed
2025-04-09 16:34:13,754 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-04-09 16:34:13,764 - DEBUG - Requesting: http://10.0.0.48:8082/domain/7668f027-eb57-47cc-91d2-f0ace78159ef
2025-04-09 16:34:13,782 - DEBUG - Requesting: http://10.0.0.48:8082/project/f9336da9-6a09-4b19-903d-33969d540d54
2025-04-09 16:34:13,869 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:13,888 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:13,902 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:13,912 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:13,921 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/d10b1025-072b-4cc8-9cec-f796d305d84b
2025-04-09 16:34:13,932 - DEBUG - Route Targets: ['target:64512:8000005']
2025-04-09 16:34:13,932 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:13,944 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:13,962 - INFO - Verified VN network id 8 for VN 077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:13,963 - INFO - Verifications in API Server for VN ctest-vn-34133273 passed
2025-04-09 16:34:13,963 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:13,976 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:13,991 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:14,002 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/d10b1025-072b-4cc8-9cec-f796d305d84b
2025-04-09 16:34:14,025 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273', 'interests': '0', 'advertised': '0', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestBasicVMVN-51285041', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '540072638487085278', 'uuid-lslong': '13546803511120185988'}, 'enable': 'true', 'created': '2025-04-09T16:34:10', 'last-modified': '2025-04-09T16:34:10', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.402573'}
2025-04-09 16:34:14,037 - DEBUG - Route Targets: ['target:64512:8000005']
2025-04-09 16:34:14,037 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-vn-34133273 passed
2025-04-09 16:34:14,038 - DEBUG - ====Verifying policy data for ctest-vn-34133273 in API_Server ======
2025-04-09 16:34:14,038 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-04-09 16:34:14,066 - DEBUG - Requesting: http://10.0.0.48:8082/domain/7668f027-eb57-47cc-91d2-f0ace78159ef
2025-04-09 16:34:14,103 - DEBUG - Requesting: http://10.0.0.48:8082/project/f9336da9-6a09-4b19-903d-33969d540d54
2025-04-09 16:34:14,235 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/077eb934-09ee-40de-bbff-ea04f2554a84
2025-04-09 16:34:14,257 - DEBUG - =>VN ctest-vn-34133273 has no policy to be verified
2025-04-09 16:34:14,257 - DEBUG - Verifying the vn in opserver
2025-04-09 16:34:14,257 - DEBUG - Verifying the default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 virtual network link through opserver 10.0.0.48
2025-04-09 16:34:14,257 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-04-09 16:34:14,626 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks
2025-04-09 16:34:14,730 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273?flat'}
2025-04-09 16:34:14,730 - INFO - Validated that VN default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 is found in opserver
2025-04-09 16:34:14,738 - DEBUG - VRF ids for VN ctest-vn-34133273: {'10.0.0.48': '2'}
2025-04-09 16:34:14,738 - INFO - Waiting for VM ctest-TestBasicVMVN-51285041-36914929 to be up..
2025-04-09 16:34:14,838 - DEBUG - VM is in ACTIVE state now
2025-04-09 16:34:14,838 - INFO - VM name : ctest-TestBasicVMVN-51285041-36914929
2025-04-09 16:34:14,925 - DEBUG - VM ctest-TestBasicVMVN-51285041-36914929 ID is a7f61e4c-eee5-483c-8f72-d0654ad4721b
2025-04-09 16:34:14,925 - DEBUG - VM ctest-TestBasicVMVN-51285041-36914929 launched on Node cn-jenkins-deploy-platform-ansible-os-3071-1
2025-04-09 16:34:15,018 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/a7f61e4c-eee5-483c-8f72-d0654ad4721b
2025-04-09 16:34:15,035 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/1c744d52-b33a-4a99-938c-b9eaadb9d578
2025-04-09 16:34:18,398 - 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-04-09 16:34:18,398 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-36914929 failed!
2025-04-09 16:34:18,461 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 is 31.1.1.1 and allocation pool is NOT set
2025-04-09 16:34:18,461 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 is 31.1.2.1 and allocation pool is NOT set
2025-04-09 16:34:22,557 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1028ms')
2025-04-09 16:34:22,558 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-36914929 failed!
2025-04-09 16:34:22,617 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 is 31.1.1.1 and allocation pool is NOT set
2025-04-09 16:34:22,617 - DEBUG - Gateway for vn default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273 is 31.1.2.1 and allocation pool is NOT set
2025-04-09 16:34:24,687 - 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.27 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.86 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.858/4.563/6.269/1.705 ms')
2025-04-09 16:34:24,687 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBasicVMVN-51285041-36914929 passed
2025-04-09 16:34:24,865 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:24,865 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-36914929, IP 31.1.1.4, Port 22
2025-04-09 16:34:24,931 - 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-04-09 16:34:25,045 - DEBUG - VM ctest-TestBasicVMVN-51285041-36914929 is NOT ready for SSH connections, VM status: ACTIVE
2025-04-09 16:34:30,046 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:30,046 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-36914929, IP 31.1.1.4, Port 22
2025-04-09 16:34:30,205 - DEBUG - VM ctest-TestBasicVMVN-51285041-36914929 is ready for SSH connections
2025-04-09 16:34:30,206 - INFO - Waiting for VM ctest-TestBasicVMVN-51285041-64140650 to be up..
2025-04-09 16:34:30,304 - DEBUG - VM is in ACTIVE state now
2025-04-09 16:34:30,304 - INFO - VM name : ctest-TestBasicVMVN-51285041-64140650
2025-04-09 16:34:30,403 - DEBUG - VM ctest-TestBasicVMVN-51285041-64140650 ID is 5c7a5f00-c142-4c47-8686-fef9c4a2b767
2025-04-09 16:34:30,404 - DEBUG - VM ctest-TestBasicVMVN-51285041-64140650 launched on Node cn-jenkins-deploy-platform-ansible-os-3071-1
2025-04-09 16:34:30,505 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/5c7a5f00-c142-4c47-8686-fef9c4a2b767
2025-04-09 16:34:30,518 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b877915c-3d4c-4492-95a3-bf9d2d001161
2025-04-09 16:34:31,786 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.34 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.507 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.507/1.925/3.343/1.418 ms')
2025-04-09 16:34:31,786 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBasicVMVN-51285041-64140650 passed
2025-04-09 16:34:31,949 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:31,949 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-64140650, IP 31.1.2.4, Port 22
2025-04-09 16:34:32,124 - DEBUG - VM ctest-TestBasicVMVN-51285041-64140650 is ready for SSH connections
2025-04-09 16:34:32,124 - INFO - Waiting for VM ctest-TestBasicVMVN-51285041-61560105 to be up..
2025-04-09 16:34:32,224 - DEBUG - VM is in ACTIVE state now
2025-04-09 16:34:32,224 - INFO - VM name : ctest-TestBasicVMVN-51285041-61560105
2025-04-09 16:34:32,322 - DEBUG - VM ctest-TestBasicVMVN-51285041-61560105 ID is b3b9e860-7839-4996-b5b7-700587690a9a
2025-04-09 16:34:32,322 - DEBUG - VM ctest-TestBasicVMVN-51285041-61560105 launched on Node cn-jenkins-deploy-platform-ansible-os-3071-1
2025-04-09 16:34:32,412 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/b3b9e860-7839-4996-b5b7-700587690a9a
2025-04-09 16:34:32,423 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/98a962f4-4bd1-4497-a86e-5246c7220956
2025-04-09 16:34:33,682 - 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=3.24 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.529 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.529/1.884/3.239/1.355 ms')
2025-04-09 16:34:33,682 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBasicVMVN-51285041-61560105 passed
2025-04-09 16:34:33,849 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:33,849 - DEBUG - Waiting to SSH to VM ctest-TestBasicVMVN-51285041-61560105, IP 31.1.1.3, Port 22
2025-04-09 16:34:34,019 - DEBUG - VM ctest-TestBasicVMVN-51285041-61560105 is ready for SSH connections
2025-04-09 16:34:34,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:34,019 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:34,019 - DEBUG - ping -s 56 -c 3 -W 1 31.1.2.4
2025-04-09 16:34:38,207 - 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.112 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=0.996 ms
64 bytes from 31.1.2.4: seq=2 ttl=63 time=1.602 ms
--- 31.1.2.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.996/2.570/5.112 ms
2025-04-09 16:34:38,207 - INFO - Ping to IP 31.1.2.4 from VM ctest-TestBasicVMVN-51285041-36914929 passed
2025-04-09 16:34:38,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:38,208 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:38,208 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.4
2025-04-09 16:34:41,927 - 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.159 ms
64 bytes from 31.1.1.4: seq=1 ttl=63 time=1.134 ms
64 bytes from 31.1.1.4: seq=2 ttl=63 time=1.092 ms
--- 31.1.1.4 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.092/1.795/3.159 ms
2025-04-09 16:34:41,927 - INFO - Ping to IP 31.1.1.4 from VM ctest-TestBasicVMVN-51285041-64140650 passed
2025-04-09 16:34:41,927 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:41,927 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:41,927 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-04-09 16:34:41,927 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:41,927 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-04-09 16:34:42,117 - DEBUG - None
2025-04-09 16:34:42,117 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:42,117 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-04-09 16:34:42,328 - DEBUG - None
2025-04-09 16:34:42,328 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:42,329 - DEBUG - echo 0 > /proc/sys/net/ipv4/icmp_echo_ignore_broadcasts
2025-04-09 16:34:43,098 - DEBUG - None
2025-04-09 16:34:43,098 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:43,099 - DEBUG - ping -s 56 -c 3 -W 1 31.1.1.7
2025-04-09 16:34:45,324 - 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.031 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=6.497 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.430 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=2.449 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.463 ms
--- 31.1.1.7 ping statistics ---
3 packets transmitted, 3 packets received, 2 duplicates, 0% packet loss
round-trip min/avg/max = 0.430/2.374/6.497 ms
2025-04-09 16:34:45,325 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:45,325 - DEBUG - ping -s 56 -c 3 -W 1 224.0.0.1
2025-04-09 16:34:47,623 - 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.463 ms
64 bytes from 31.1.1.3: seq=0 ttl=64 time=4.409 ms (DUP!)
64 bytes from 31.1.2.4: seq=0 ttl=63 time=5.910 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.486 ms
64 bytes from 31.1.2.4: seq=1 ttl=63 time=2.484 ms (DUP!)
64 bytes from 31.1.1.3: seq=1 ttl=64 time=4.044 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.569 ms
--- 224.0.0.1 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.486/2.766/5.910 ms
2025-04-09 16:34:47,624 - 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.48, gateway password: c0ntrail123
2025-04-09 16:34:47,624 - DEBUG - ping -s 56 -c 3 -W 1 255.255.255.255
2025-04-09 16:34:49,872 - 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.711 ms
64 bytes from 31.1.2.4: seq=0 ttl=63 time=2.802 ms (DUP!)
64 bytes from 31.1.1.3: seq=0 ttl=64 time=3.787 ms (DUP!)
64 bytes from 31.1.1.4: seq=1 ttl=64 time=0.603 ms
64 bytes from 31.1.1.3: seq=1 ttl=64 time=1.845 ms (DUP!)
64 bytes from 31.1.2.4: seq=1 ttl=63 time=3.310 ms (DUP!)
64 bytes from 31.1.1.4: seq=2 ttl=64 time=0.532 ms
--- 255.255.255.255 ping statistics ---
3 packets transmitted, 3 packets received, 4 duplicates, 0% packet loss
round-trip min/avg/max = 0.532/1.941/3.787 ms
2025-04-09 16:34:49,873 - INFO - Deleting VM ctest-TestBasicVMVN-51285041-61560105
2025-04-09 16:34:49,951 - INFO - Detaching port b877915c-3d4c-4492-95a3-bf9d2d001161 from VM ctest-TestBasicVMVN-51285041-64140650
2025-04-09 16:34:50,024 - INFO - Deleting VM ctest-TestBasicVMVN-51285041-64140650
2025-04-09 16:34:50,112 - INFO - Detaching port 1c744d52-b33a-4a99-938c-b9eaadb9d578 from VM ctest-TestBasicVMVN-51285041-36914929
2025-04-09 16:34:50,164 - INFO - Deleting VM ctest-TestBasicVMVN-51285041-36914929
2025-04-09 16:34:50,240 - INFO - Deleting VN ctest-vn-34133273
2025-04-09 16:34:50,464 - DEBUG - Response for delete_port : ()
2025-04-09 16:34:50,687 - DEBUG - Response for delete_port : ()
2025-04-09 16:34:50,741 - DEBUG - VN 077eb934-09ee-40de-bbff-ea04f2554a84 still in use: Unable to complete operation on network 077eb934-09ee-40de-bbff-ea04f2554a84. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9607383a-2138-4f7c-abdb-2e6ec0f168d7']
2025-04-09 16:34:50,742 - WARNING - Deleting VN ctest-vn-34133273 failed..Will retry
2025-04-09 16:34:52,944 - DEBUG - Response for deleting network ()
2025-04-09 16:34:52,944 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/0c0ae8ba-8c2f-4b9d-b9c8-b7df5d410a6a
2025-04-09 16:34:52,952 - DEBUG - Response Code: 404
2025-04-09 16:34:52,952 - DEBUG - Requesting: http://10.0.0.48:8082/domains
2025-04-09 16:34:52,959 - DEBUG - Requesting: http://10.0.0.48:8082/domain/7668f027-eb57-47cc-91d2-f0ace78159ef
2025-04-09 16:34:53,015 - DEBUG - Requesting: http://10.0.0.48:8082/project/f9336da9-6a09-4b19-903d-33969d540d54
2025-04-09 16:34:53,096 - INFO - Validated that VN ctest-vn-34133273 is not found in API Server
2025-04-09 16:34:53,115 - DEBUG - VN ctest-vn-34133273 is not present in Agent 10.0.0.48
2025-04-09 16:34:53,115 - INFO - Validated that VN ctest-vn-34133273 is not in any agent
2025-04-09 16:34:53,139 - DEBUG - VRF 2 is not seen in agent 10.0.0.48
2025-04-09 16:34:53,154 - DEBUG - Vrouter 10.0.0.48 does not have vrf 2 for VN ctest-vn-34133273
2025-04-09 16:34:53,154 - INFO - Validated that all vrouters do not have the route table for VN default-domain:ctest-TestBasicVMVN-51285041:ctest-vn-34133273
2025-04-09 16:34:53,170 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-vn-34133273 info
2025-04-09 16:34:53,256 - INFO - IPAM: ctest-ipam-93035228 is not found in API Server
2025-04-09 16:34:53,270 - INFO - IPAM:ctest-ipam-93035228 is not found in control node
2025-04-09 16:34:53,549 - DEBUG - No XMPP flaps were noticed during the test
2025-04-09 16:34:53,549 - INFO - END TEST : test_ping_within_vn_two_vms_two_different_subnets : PASSED[0:00:44]
2025-04-09 16:34:53,549 - INFO - --------------------------------------------------------------------------------
2025-04-09 16:34:54,276 - INFO - Deleted project: ctest-TestBasicVMVN-51285041, ID : f9336da9-6a09-4b19-903d-33969d540d54