is in ACTIVE state now
2025-07-09 14:19:27,862 - INFO - VM name : ctest-vm2-test-31441696
2025-07-09 14:19:28,025 - DEBUG - VM ctest-vm2-test-31441696 ID is 8580930c-8df1-48c2-867d-a58354419dba
2025-07-09 14:19:28,025 - DEBUG - VM ctest-vm2-test-31441696 launched on Node cn-jenkins-deploy-platform-ansible-os-3619-1
2025-07-09 14:19:28,173 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/8580930c-8df1-48c2-867d-a58354419dba
2025-07-09 14:19:28,191 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/582dcd61-fb4e-4d1f-aead-92933fa136fb
2025-07-09 14:19:31,527 - 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 1016ms')
2025-07-09 14:19:31,527 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-31441696 failed!
2025-07-09 14:19:31,588 - DEBUG - Gateway for vn default-domain:ctest-TestvDNSBasic0-78871517:ctest-vn1-vdns-93845732 is 10.10.10.1 and allocation pool is NOT set
2025-07-09 14:19:35,687 - 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 1021ms')
2025-07-09 14:19:35,688 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-31441696 failed!
2025-07-09 14:19:35,768 - DEBUG - Gateway for vn default-domain:ctest-TestvDNSBasic0-78871517:ctest-vn1-vdns-93845732 is 10.10.10.1 and allocation pool is NOT set
2025-07-09 14:19:37,847 - 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=9.86 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.749 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.749/5.302/9.855/4.553 ms')
2025-07-09 14:19:37,847 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vm2-test-31441696 passed
2025-07-09 14:19:38,021 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-09 14:19:38,021 - DEBUG - Waiting to SSH to VM ctest-vm2-test-31441696, IP 10.10.10.4, Port 22
2025-07-09 14:19:38,220 - DEBUG - VM ctest-vm2-test-31441696 is ready for SSH connections
2025-07-09 14:19:38,220 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:38,220 - DEBUG - ifconfig | grep 10.10.10.4
2025-07-09 14:19:39,166 - DEBUG - inet addr:10.10.10.4 Bcast:10.10.10.255 Mask:255.255.255.0
2025-07-09 14:19:39,166 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-31441696", None, socket.AF_INET6)', host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-07-09 14:19:39,166 - DEBUG - python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-31441696", None, socket.AF_INET6)'
2025-07-09 14:19:39,446 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 ctest-vm2-test-31441696, host_string: cirros@169.254.0.4, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-07-09 14:19:39,446 - DEBUG - ping -s 56 -c 3 -W 1 ctest-vm2-test-31441696
2025-07-09 14:19:41,842 - DEBUG - PING ctest-vm2-test-31441696 (10.10.10.4): 56 data bytes
64 bytes from 10.10.10.4: seq=0 ttl=64 time=4.387 ms
64 bytes from 10.10.10.4: seq=1 ttl=64 time=0.575 ms
64 bytes from 10.10.10.4: seq=2 ttl=64 time=1.514 ms
--- ctest-vm2-test-31441696 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.575/2.158/4.387 ms
2025-07-09 14:19:41,842 - INFO - Ping to IP ctest-vm2-test-31441696 from VM ctest-vm2-test-31441696 passed
2025-07-09 14:19:41,872 - DEBUG - Requesting: http://10.0.0.65:8092/Snh_PageReq?x=AllEntries%20VdnsServersReq
2025-07-09 14:19:41,883 - DEBUG - Requesting: http://10.0.0.65:8092/Snh_PageReq?x=default-domain:ctest-vdns1-71811902@0%20AllEntriesVdnsRecordsReq
2025-07-09 14:19:41,898 - DEBUG - Actual record data {'name': 'default-domain:ctest-vdns1-71811902:1:ctest-vm2-test-31441696:10.10.10.4', 'rec_name': 'ctest-vm2-test-31441696', '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-3619-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-31441696.juniper.net', 'installed': 'yes', 'zone': 'juniper.net'}
2025-07-09 14:19:41,898 - DEBUG - Actual record data {'name': 'default-domain:ctest-vdns1-71811902:12:4.10.10.10.in-addr.arpa:ctest-vm2-test-31441696', 'rec_name': '4.10.10.10.in-addr.arpa', 'rec_type': 'PTR', 'rec_class': 'IN', 'rec_data': 'ctest-vm2-test-31441696', 'rec_ttl': '100', 'source': 'Agent', 'installed': 'true', 'flags': '3', 'rec_source_name': 'cn-jenkins-deploy-platform-ansible-os-3619-1/dns'} ,
Expected record data {'rec_data': 'ctest-vm2-test-31441696.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'}
2025-07-09 14:19:41,899 - DEBUG - Running remote_cmd, Cmd : python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-31441696", None, socket.AF_INET6)', host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-07-09 14:19:41,899 - DEBUG - python -c 'import socket;socket.getaddrinfo("ctest-vm2-test-31441696", None, socket.AF_INET6)'
2025-07-09 14:19:42,163 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 ctest-vm2-test-31441696, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-07-09 14:19:42,164 - DEBUG - ping -s 56 -c 3 -W 1 ctest-vm2-test-31441696
2025-07-09 14:19:44,429 - DEBUG - PING ctest-vm2-test-31441696 (10.10.10.4): 56 data bytes
64 bytes from 10.10.10.4: seq=0 ttl=64 time=9.494 ms
64 bytes from 10.10.10.4: seq=1 ttl=64 time=1.908 ms
64 bytes from 10.10.10.4: seq=2 ttl=64 time=1.676 ms
--- ctest-vm2-test-31441696 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.676/4.359/9.494 ms
2025-07-09 14:19:44,430 - INFO - Ping to IP ctest-vm2-test-31441696 from VM ctest-vm1-test-59600891 passed
2025-07-09 14:19:44,430 - INFO - Try deleting the VDNS entry ctest-vdns1-71811902 with back ref of ipam.
2025-07-09 14:19:44,500 - DEBUG - Delete when resource still referred: ['http://10.0.0.65:8082/network-ipam/ee02ef4d-d7f9-4d1d-8b4f-d13fa07a6201']
2025-07-09 14:19:44,500 - INFO - Deletion of the vdns entry failed with back ref of ipam as expected
2025-07-09 14:19:44,500 - INFO - Add CNAME VDNS record for ctest-vm1-test-59600891 and verify we able to ping by alias name
2025-07-09 14:19:44,552 - DEBUG - Creating VDNS record data : test-rec
2025-07-09 14:19:44,684 - INFO - In verify_on_setup
2025-07-09 14:19:44,792 - DEBUG - VDNS record: ['default-domain', 'ctest-vdns1-71811902', 'test-rec'] created succesfully
2025-07-09 14:19:44,792 - INFO - Verify VDNS record is shown in the API server
2025-07-09 14:19:44,793 - DEBUG - Requesting: http://10.0.0.65:8082/domains
2025-07-09 14:19:44,805 - DEBUG - Requesting: http://10.0.0.65:8082/domain/efe773b4-0e10-4caa-a961-3036404beeb6
2025-07-09 14:19:44,828 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-DNS/a21ab6bc-1b7f-4f23-ab9f-442558900e4d
2025-07-09 14:19:44,845 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-DNS-record/19612d84-9e64-4fb2-a6f4-210c05ac79d9
2025-07-09 14:19:44,861 - INFO - Verify VDNS record is shown in the control node
2025-07-09 14:19:44,873 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:44,874 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2025-07-09 14:19:45,117 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:45,117 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2025-07-09 14:19:45,404 - DEBUG - ping: bad address 'vm1-test-alias'
2025-07-09 14:19:45,404 - WARNING - Ping to IP vm1-test-alias from VM ctest-vm1-test-59600891 failed
2025-07-09 14:19:46,405 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:46,405 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2025-07-09 14:19:46,685 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:46,685 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2025-07-09 14:19:47,015 - DEBUG - ping: bad address 'vm1-test-alias'
2025-07-09 14:19:47,016 - WARNING - Ping to IP vm1-test-alias from VM ctest-vm1-test-59600891 failed
2025-07-09 14:19:48,017 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:48,017 - DEBUG - python -c 'import socket;socket.getaddrinfo("vm1-test-alias", None, socket.AF_INET6)'
2025-07-09 14:19:48,341 - 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.65, gateway password: c0ntrail123
2025-07-09 14:19:48,341 - DEBUG - ping -s 56 -c 3 -W 1 vm1-test-alias
2025-07-09 14:19:50,694 - 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.565 ms
64 bytes from 10.10.10.3: seq=1 ttl=64 time=1.033 ms
64 bytes from 10.10.10.3: seq=2 ttl=64 time=0.626 ms
--- vm1-test-alias ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 0.565/0.741/1.033 ms
2025-07-09 14:19:50,694 - INFO - Ping to IP vm1-test-alias from VM ctest-vm1-test-59600891 passed
2025-07-09 14:19:50,695 - DEBUG - Deleting VDNS record data: test-rec
2025-07-09 14:19:50,800 - INFO - VDNS record info: default-domain:ctest-vdns1-71811902:test-rec deleted successfully.
2025-07-09 14:19:50,801 - INFO - Deleting VM ctest-vm2-test-31441696
2025-07-09 14:19:50,923 - INFO - Deleting VM ctest-vm1-test-59600891
2025-07-09 14:19:51,063 - INFO - Deleting VN ctest-vn1-vdns-93845732
2025-07-09 14:19:51,140 - DEBUG - VN b1292d61-c7ed-4baa-a16c-f0a9a1968c86 still in use: Unable to complete operation on network b1292d61-c7ed-4baa-a16c-f0a9a1968c86. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-36565355-cc3e-480c-b65c-64e72a8ec8b7']
2025-07-09 14:19:51,140 - WARNING - Deleting VN ctest-vn1-vdns-93845732 failed..Will retry
2025-07-09 14:19:53,533 - DEBUG - Response for deleting network ()
2025-07-09 14:19:53,582 - DEBUG - Deleting VDNS Entry: ctest-vdns1-71811902
2025-07-09 14:19:53,668 - INFO - VDNS info: ['default-domain', 'ctest-vdns1-71811902'] deleted successfully.
2025-07-09 14:19:53,668 - DEBUG - Requesting: http://10.0.0.65:8082/domains
2025-07-09 14:19:53,677 - DEBUG - Requesting: http://10.0.0.65:8082/domain/efe773b4-0e10-4caa-a961-3036404beeb6
2025-07-09 14:19:53,694 - DEBUG - VDNS information not found in API server
2025-07-09 14:19:53,694 - INFO - VDNS information ctest-vdns1-71811902 removed from the API Server
2025-07-09 14:19:53,702 - INFO - VDNS information ctest-vdns1-71811902 removed in the Control node
2025-07-09 14:19:53,702 - DEBUG - Skipping the deletion of Project ['default-domain', 'ctest-TestvDNSBasic0-78871517']
2025-07-09 14:19:54,028 - DEBUG - No XMPP flaps were noticed during the test
2025-07-09 14:19:54,028 - INFO - END TEST : test_vdns_ping_same_vn : PASSED[0:00:58]
2025-07-09 14:19:54,029 - INFO - --------------------------------------------------------------------------------
2025-07-09 14:19:55,035 - INFO - Deleted project: ctest-TestvDNSBasic0-78871517, ID : 4ea1aba2-b650-4874-9d48-1cf63f32b4e9