KVM (uvt) test fails because of SSH failure

[Rod Smith]

The KVM (UVT) virtualization test is failing on Bionic (but not on Focal), with the following output:

$ sudo ./virtualization.py uvt
WARNING:root:UVT_IMAGE_OR_SOURCE is not set
ERROR:root:Command uvt-kvm wait tmp2sxuv07r returnd a code of 1
ERROR:root: STDOUT:
ERROR:root: STDERR: ubuntu@192.168.122.146: Permission denied (publickey).
Traceback (most recent call last):
  File "/usr/bin/uvt-kvm", line 35, in <module>
    uvtool.libvirt.kvm.main_cli_wrapper(sys.argv[1:])
  File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 861, in main_cli_wrapper
    main(*args, **kwargs)
  File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 856, in main
    args.func(parser, args)
  File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 782, in main_wait
    main_wait_remote(parser, args)
  File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 745, in main_wait_remote
    insecure=args.insecure,
  File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 614, in ssh
    ssh_call, preexec_fn=subprocess_setup, close_fds=True, stdin=stdin
  File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '[u'ssh', u'-o', u'UserKnownHostsFile=/tmp/uvt-kvm.known_hoststmpJzYIDL', u'-l', u'ubuntu', u'192.168.122.146', u'env', u'UVTOOL_WAIT_INTERVAL=8.0', u'UVTOOL_WAIT_TIMEOUT=120.0', u'sh', u'-']' returned non-zero exit status 255

FAIL: VM was not started and/or checked

Note that even getting to this point currently requires a change to the script to fix bug #1931586; that fix is provided in the following MR:

https://code.launchpad.net/~rodsmith/plainbox-provider-checkbox/+git/plainbox-provider-checkbox/+merge/404396

[Jeff Lane  (bladernr)]

Just a heads up:

Joc has a MR in flight that modifies the SSH command and
key-generation stuff to fix this in Snaps. It's probably worth
cloning his branch and testing out his modified virtualization.py to
see if that resolves your SSH bug as well.

https://code.launchpad.net/~jocave/plainbox-provider-checkbox/+git/plainbox-provider-checkbox/+merge/403636

Jeff

On Fri, Jun 18, 2021 at 10:50 AM Rod Smith <email address hidden> wrote:
>
> ** Tags added: hwcert-server
>
> --
> You received this bug notification because you are subscribed to
> Provider for Plainbox - Checkbox.
> Matching subscriptions: Checkbox Project - All, PPC Bugs
> https://bugs.launchpad.net/bugs/1932550
>
> Title:
> KVM (uvt) test fails because of SSH failure
>
> Status in Provider for Plainbox - Checkbox:
> New
>
> Bug description:
> The KVM (UVT) virtualization test is failing on Bionic (but not on
> Focal), with the following output:
>
> $ sudo ./virtualization.py uvt
> WARNING:root:UVT_IMAGE_OR_SOURCE is not set
> ERROR:root:Command uvt-kvm wait tmp2sxuv07r returnd a code of 1
> ERROR:root: STDOUT:
> ERROR:root: STDERR: ubuntu@192.168.122.146: Permission denied (publickey).
> Traceback (most recent call last):
> File "/usr/bin/uvt-kvm", line 35, in <module>
> uvtool.libvirt.kvm.main_cli_wrapper(sys.argv[1:])
> File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 861, in main_cli_wrapper
> main(*args, **kwargs)
> File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 856, in main
> args.func(parser, args)
> File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 782, in main_wait
> main_wait_remote(parser, args)
> File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 745, in main_wait_remote
> insecure=args.insecure,
> File "/usr/lib/python2.7/dist-packages/uvtool/libvirt/kvm.py", line 614, in ssh
> ssh_call, preexec_fn=subprocess_setup, close_fds=True, stdin=stdin
> File "/usr/lib/python2.7/subprocess.py", line 190, in check_call
> raise CalledProcessError(retcode, cmd)
> subprocess.CalledProcessError: Command '[u'ssh', u'-o', u'UserKnownHostsFile=/tmp/uvt-kvm.known_hoststmpJzYIDL', u'-l', u'ubuntu', u'192.168.122.146', u'env', u'UVTOOL_WAIT_INTERVAL=8.0', u'UVTOOL_WAIT_TIMEOUT=120.0', u'sh', u'-']' returned non-zero exit status 255
>
> FAIL: VM was not started and/or checked
>
> Note that even getting to this point currently requires a change to
> the script to fix bug #1931586; that fix is provided in the following
> MR:
>
> https://code.launchpad.net/~rodsmith/plainbox-provider-checkbox/+git
> /plainbox-provider-checkbox/+merge/404396
>
> To manage notifications about this bug go to:
> https://bugs.launchpad.net/plainbox-provider-checkbox/+bug/1932550/+subscriptions
>
> Launchpad-Notification-Type: bug
> Launchpad-Bug: product=plainbox-provider-checkbox; status=New; importance=Undecided; assignee=None;
> Launchpad-Bug-Tags: hwcert-server
> Launchpad-Bug-Information-Type: Public
> Launchpad-Bug-Private: no
> Launchpad-Bug-Security-Vulnerability: no
> Launchpad-Bug-Commenters: rodsmith
> Launchpad-Bug-Rep...

[Rod Smith (rodsmith)]

This problem has disappeared for me. It's possible that Joc's MR, referenced by Jeff above, may have fixed it; or maybe it's a temperamental bug. If it re-appears, feel free to re-open this bug report or file a new one.

[Jeff Lane  (bladernr)]

Sylvain did just a release to stable that should include JoC's updates
to the script, so hopefully that does resolve it for everyone.

--
Jeff Lane
Engineering Manager
IHV/OEM Alliances and Server Certification

"Entropy isn't what it used to be."