is in ACTIVE state now
2026-03-05 12:51:26,992 - INFO - VM name : ctest-vm2-test-25767741
2026-03-05 12:51:27,080 - DEBUG - VM ctest-vm2-test-25767741 ID is c6ae442a-f52c-474d-9115-ff80913e187d
2026-03-05 12:51:27,081 - DEBUG - VM ctest-vm2-test-25767741 launched on Node cn-jenkins-deploy-platform-ansible-os-5441-1
2026-03-05 12:51:27,172 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/c6ae442a-f52c-474d-9115-ff80913e187d
2026-03-05 12:51:27,183 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/e59b4308-aadc-4783-b04f-7cef02e3d33f
2026-03-05 12:51:30,436 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2026-03-05 12:51:30,436 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-25767741 failed!
2026-03-05 12:51:30,492 - DEBUG - Gateway for vn default-domain:ctest-TestvDNSBasic0-20683009:ctest-vn1-vdns-73110366 is 10.10.10.1 and allocation pool is NOT set
2026-03-05 12:51:34,567 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms')
2026-03-05 12:51:34,567 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-25767741 failed!
2026-03-05 12:51:34,624 - DEBUG - Gateway for vn default-domain:ctest-TestvDNSBasic0-20683009:ctest-vn1-vdns-73110366 is 10.10.10.1 and allocation pool is NOT set
2026-03-05 12:51:36,689 - 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.43 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.609 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.609/2.017/3.426/1.408 ms')
2026-03-05 12:51:36,689 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-25767741 passed
2026-03-05 12:51:36,844 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-05 12:51:36,844 - DEBUG - Waiting to SSH to VM ctest-vm2-test-25767741, IP 10.10.10.4, Port 22
2026-03-05 12:51:37,010 - DEBUG - VM ctest-vm2-test-25767741 is ready for SSH connections
2026-03-05 12:51:37,010 - DEBUG - Running remote_cmd, Cmd : ifconfig | grep 10.10.10.4, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:37,010 - DEBUG - ifconfig | grep 10.10.10.4
2026-03-05 12:51:38,730 - DEBUG - inet addr:10.10.10.4 Bcast:10.10.10.255 Mask:255.255.255.0
2026-03-05 12:51:38,730 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-25767741", None, socket.AF_INET6)', host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:38,731 - DEBUG - python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-25767741", None, socket.AF_INET6)'
2026-03-05 12:51:39,015 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 ctest-vm2-test-25767741, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:39,015 - DEBUG - ping -s 56 -c 3 -W 1 ctest-vm2-test-25767741
2026-03-05 12:51:41,301 - DEBUG - PING ctest-vm2-test-25767741 (10.10.10.4): 56 data bytes
64 bytes from 10.10.10.4: seq=0 ttl=64 time=3.252 ms
64 bytes from 10.10.10.4: seq=1 ttl=64 time=0.378 ms
64 bytes from 10.10.10.4: seq=2 ttl=64 time=0.631 ms
--- ctest-vm2-test-25767741 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.378/1.420/3.252 ms
2026-03-05 12:51:41,301 - INFO - Ping to IP ctest-vm2-test-25767741 from VM ctest-vm2-test-25767741 passed
2026-03-05 12:51:41,316 - DEBUG - Requesting: http://10.0.0.33:8092/Snh_PageReq?x=AllEntries%20VdnsServersReq
2026-03-05 12:51:41,322 - DEBUG - Requesting: http://10.0.0.33:8092/Snh_PageReq?x=default-domain:ctest-vdns1-24066460@0%20AllEntriesVdnsRecordsReq
2026-03-05 12:51:41,328 - DEBUG - Actual record data {'name': 'default-domain:ctest-vdns1-24066460:1:ctest-vm2-test-25767741:10.10.10.4', 'rec_name': 'ctest-vm2-test-25767741', 'rec_type': 'A', 'rec_class': 'IN', 'rec_data': '10.10.10.4', 'rec_ttl': '100', 'source': 'Agent', 'installed': 'true', 'flags': '3', 'rec_source_name': 'cn-jenkins-deploy-platform-ansible-os-5441-1/dns'} ,
Expected record data {'rec_data': '10.10.10.4', 'rec_type': 'A', 'rec_class': 'IN', 'rec_ttl': '100', 'rec_name': 'ctest-vm2-test-25767741.juniper.net', 'installed': 'yes', 'zone': 'juniper.net'}
2026-03-05 12:51:41,328 - DEBUG - Actual record data {'name': 'default-domain:ctest-vdns1-24066460:12:4.10.10.10.in-addr.arpa:ctest-vm2-test-25767741', 'rec_name': '4.10.10.10.in-addr.arpa', 'rec_type': 'PTR', 'rec_class': 'IN', 'rec_data': 'ctest-vm2-test-25767741', 'rec_ttl': '100', 'source': 'Agent', 'installed': 'true', 'flags': '3', 'rec_source_name': 'cn-jenkins-deploy-platform-ansible-os-5441-1/dns'} ,
Expected record data {'rec_data': 'ctest-vm2-test-25767741.juniper.net', 'rec_type': 'PTR', 'rec_class': 'IN', 'rec_ttl': '100', 'rec_name': '4.10.10.10.in-addr.arpa', 'installed': 'yes', 'zone': '10.10.10.in-addr.arpa'}
2026-03-05 12:51:41,328 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-25767741", None, socket.AF_INET6)', host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:41,328 - DEBUG - python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-25767741", None, socket.AF_INET6)'
2026-03-05 12:51:41,537 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 ctest-vm2-test-25767741, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:41,537 - DEBUG - ping -s 56 -c 3 -W 1 ctest-vm2-test-25767741
2026-03-05 12:51:43,836 - DEBUG - PING ctest-vm2-test-25767741 (10.10.10.4): 56 data bytes
64 bytes from 10.10.10.4: seq=0 ttl=64 time=6.547 ms
64 bytes from 10.10.10.4: seq=1 ttl=64 time=0.908 ms
64 bytes from 10.10.10.4: seq=2 ttl=64 time=1.138 ms
--- ctest-vm2-test-25767741 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.908/2.864/6.547 ms
2026-03-05 12:51:43,836 - INFO - Ping to IP ctest-vm2-test-25767741 from VM ctest-vm1-test-29479151 passed
2026-03-05 12:51:43,837 - INFO - Try deleting the VDNS entry ctest-vdns1-24066460 with back ref of ipam.
2026-03-05 12:51:43,896 - DEBUG - Delete when resource still referred: ['http://10.0.0.33:8082/network-ipam/cfa94314-c28c-4338-8aba-8f5d8fe7f02f']
2026-03-05 12:51:43,896 - INFO - Deletion of the vdns entry failed with back ref of ipam as expected
2026-03-05 12:51:43,896 - INFO - Add CNAME VDNS record for ctest-vm1-test-29479151 and verify we able to ping by alias name
2026-03-05 12:51:43,944 - DEBUG - Creating VDNS record data : test-rec
2026-03-05 12:51:44,056 - INFO - In verify_on_setup
2026-03-05 12:51:44,152 - DEBUG - VDNS record: ['default-domain', 'ctest-vdns1-24066460', 'test-rec'] created succesfully
2026-03-05 12:51:44,152 - INFO - Verify VDNS record is shown in the API server
2026-03-05 12:51:44,152 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2026-03-05 12:51:44,159 - DEBUG - Requesting: http://10.0.0.33:8082/domain/4e3b2f1f-24d3-4ce6-b6de-9e97fb4e27a2
2026-03-05 12:51:44,174 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-DNS/15ae65fd-8aa7-4c9e-98f5-9e6ae8b673c3
2026-03-05 12:51:44,183 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-DNS-record/c561665c-2578-49b7-8e0c-c0813b6dc8c9
2026-03-05 12:51:44,190 - INFO - Verify VDNS record is shown in the control node
2026-03-05 12:51:44,196 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)', host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:44,196 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2026-03-05 12:51:44,411 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 vm1-test-alias, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:44,411 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2026-03-05 12:51:44,685 - DEBUG - ping: bad address 'vm1-test-alias'
2026-03-05 12:51:44,685 - WARNING - Ping to IP vm1-test-alias from VM ctest-vm1-test-29479151 failed
2026-03-05 12:51:45,686 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)', host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:45,686 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2026-03-05 12:51:45,918 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 vm1-test-alias, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:45,918 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2026-03-05 12:51:46,190 - DEBUG - ping: bad address 'vm1-test-alias'
2026-03-05 12:51:46,190 - WARNING - Ping to IP vm1-test-alias from VM ctest-vm1-test-29479151 failed
2026-03-05 12:51:47,191 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)', host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:47,191 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2026-03-05 12:51:47,401 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 vm1-test-alias, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-03-05 12:51:47,401 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2026-03-05 12:51:49,710 - DEBUG - PING vm1-test-alias (10.10.10.3): 56 data bytes
64 bytes from 10.10.10.3: seq=0 ttl=64 time=0.438 ms
64 bytes from 10.10.10.3: seq=1 ttl=64 time=0.555 ms
64 bytes from 10.10.10.3: seq=2 ttl=64 time=0.411 ms
--- vm1-test-alias ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.411/0.468/0.555 ms
2026-03-05 12:51:49,710 - INFO - Ping to IP vm1-test-alias from VM ctest-vm1-test-29479151 passed
2026-03-05 12:51:49,710 - DEBUG - Deleting VDNS record data: test-rec
2026-03-05 12:51:49,788 - INFO - VDNS record info: default-domain:ctest-vdns1-24066460:test-rec deleted successfully.
2026-03-05 12:51:49,788 - INFO - Deleting VM ctest-vm2-test-25767741
2026-03-05 12:51:49,882 - INFO - Deleting VM ctest-vm1-test-29479151
2026-03-05 12:51:49,988 - INFO - Deleting VN ctest-vn1-vdns-73110366
2026-03-05 12:51:50,038 - DEBUG - VN ad5e04c4-f903-4bd6-8577-a63e7d219888 still in use: Unable to complete operation on network ad5e04c4-f903-4bd6-8577-a63e7d219888. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-b13df22e-0cb2-4a0a-992a-8eaa8debdeed']
2026-03-05 12:51:50,038 - WARNING - Deleting VN ctest-vn1-vdns-73110366 failed..Will retry
2026-03-05 12:51:52,253 - DEBUG - Response for deleting network ()
2026-03-05 12:51:52,295 - DEBUG - Deleting VDNS Entry: ctest-vdns1-24066460
2026-03-05 12:51:52,372 - INFO - VDNS info: ['default-domain', 'ctest-vdns1-24066460'] deleted successfully.
2026-03-05 12:51:52,372 - DEBUG - Requesting: http://10.0.0.33:8082/domains
2026-03-05 12:51:52,379 - DEBUG - Requesting: http://10.0.0.33:8082/domain/4e3b2f1f-24d3-4ce6-b6de-9e97fb4e27a2
2026-03-05 12:51:52,392 - DEBUG - VDNS information not found in API server
2026-03-05 12:51:52,392 - INFO - VDNS information ctest-vdns1-24066460 removed from the API Server
2026-03-05 12:51:52,399 - INFO - VDNS information ctest-vdns1-24066460 removed in the Control node
2026-03-05 12:51:52,399 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestvDNSBasic0-20683009']
2026-03-05 12:51:52,658 - DEBUG - No XMPP flaps were noticed during the test
2026-03-05 12:51:52,659 - INFO - END TEST : test_vdns_ping_same_vn : PASSED[0:00:56]
2026-03-05 12:51:52,659 - INFO - --------------------------------------------------------------------------------
2026-03-05 12:51:53,417 - INFO - Deleted project: ctest-TestvDNSBasic0-20683009, ID : 1905ceca-3c72-4a34-8b8f-618e3155148e