autotest/tp-libvirt

numatune failures for cgroup.stop testcases

sathnaga opened this issue · 0 comments

numatune.qemu.qcow2.scsi.smp2.virtio_net.Fedora.24.ppc64le.powerkvm-libvirt.virsh.numatune.negative_testing.set_numa_parameter.running_guest.cgroup.stop
numatune.qemu.qcow2.scsi.smp2.virtio_net.Fedora.24.ppc64le.powerkvm-libvirt.virsh.numatune.negative_testing.get_numa_parameter.running_guest.cgroup.stop

These testcases fails as, systemd mounts cgroup bydefault in the system and cgconfig service does not affect the mounted cgroups.

2016-09-26 07:41:26,296 process          L0421 DEBUG| [stdout] systemd
2016-09-26 07:41:26,297 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:26,297 process          L0334 INFO | Running 'systemctl status cgconfig.service'
2016-09-26 07:41:26,307 process          L0421 DEBUG| [stdout] * cgconfig.service - Control Group configuration service
2016-09-26 07:41:26,307 process          L0435 INFO | Command 'systemctl status cgconfig.service' finished with 0 after 0.00750517845154s
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]    Loaded: loaded (/usr/lib/systemd/system/cgconfig.service; disabled; vendor preset: disabled)
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]    Active: active (exited) since Mon 2016-09-26 07:40:39 AKDT; 46s ago
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]   Process: 139135 ExecStart=/usr/sbin/cgconfigparser -l /etc/cgconfig.conf -L /etc/cgconfig.d -s 1664 (code=exited, status=0/SUCCESS)
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]  Main PID: 139135 (code=exited, status=0/SUCCESS)
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]    Memory: 0B
2016-09-26 07:41:26,308 process          L0421 DEBUG| [stdout]    CGroup: /system.slice/cgconfig.service
2016-09-26 07:41:56,362 libvirt_vm       L1787 DEBUG| VM is down
2016-09-26 07:41:56,363 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:56,364 process          L0334 INFO | Running 'systemctl stop cgconfig.service'
2016-09-26 07:41:56,411 process          L0435 INFO | Command 'systemctl stop cgconfig.service' finished with 0 after 0.0437998771667s
2016-09-26 07:41:56,411 process          L0334 INFO | Running 'true'
2016-09-26 07:41:56,415 process          L0435 INFO | Command 'true' finished with 0 after 0.000420093536377s
2016-09-26 07:41:56,415 process          L0334 INFO | Running 'ps -o comm 1'
2016-09-26 07:41:56,479 process          L0421 DEBUG| [stdout] COMMAND
2016-09-26 07:41:56,479 process          L0435 INFO | Command 'ps -o comm 1' finished with 0 after 0.0614249706268s
2016-09-26 07:41:56,480 process          L0421 DEBUG| [stdout] systemd
2016-09-26 07:41:56,480 utils_libvirtd   L0321 WARNI| This function was deprecated, Please use class utils_libvirtd.Libvirtd to manage libvirtd service.
2016-09-26 07:41:56,480 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:56,480 process          L0334 INFO | Running 'systemctl reset-failed libvirtd.service'
2016-09-26 07:41:56,486 process          L0435 INFO | Command 'systemctl reset-failed libvirtd.service' finished with 0 after 0.00320601463318s
2016-09-26 07:41:56,487 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:56,487 process          L0334 INFO | Running 'systemctl restart libvirtd.service'
2016-09-26 07:41:56,526 process          L0435 INFO | Command 'systemctl restart libvirtd.service' finished with 0 after 0.0359261035919s
2016-09-26 07:41:56,526 process          L0334 INFO | Running 'virsh list'
2016-09-26 07:41:57,179 process          L0421 DEBUG| [stdout]  Id    Name                           State
2016-09-26 07:41:57,180 process          L0435 INFO | Command 'virsh list' finished with 0 after 0.650489091873s
2016-09-26 07:41:57,180 process          L0421 DEBUG| [stdout] ----------------------------------------------------
2016-09-26 07:41:57,180 process          L0421 DEBUG| [stdout]
2016-09-26 07:41:57,180 process          L0334 INFO | Running 'true'
2016-09-26 07:41:57,184 process          L0435 INFO | Command 'true' finished with 0 after 0.000392913818359s
2016-09-26 07:41:57,184 process          L0334 INFO | Running 'ps -o comm 1'
2016-09-26 07:41:57,244 process          L0421 DEBUG| [stdout] COMMAND
2016-09-26 07:41:57,244 process          L0435 INFO | Command 'ps -o comm 1' finished with 0 after 0.0573031902313s
2016-09-26 07:41:57,244 process          L0421 DEBUG| [stdout] systemd
2016-09-26 07:41:57,245 utils_libvirtd   L0321 WARNI| This function was deprecated, Please use class utils_libvirtd.Libvirtd to manage libvirtd service.
2016-09-26 07:41:57,245 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:57,245 process          L0334 INFO | Running 'systemctl status libvirtd.service'
2016-09-26 07:41:57,255 process          L0421 DEBUG| [stdout] * libvirtd.service - Virtualization daemon
2016-09-26 07:41:57,255 process          L0421 DEBUG| [stdout]    Loaded: loaded (/usr/lib/systemd/system/libvirtd.service; enabled; vendor preset: enabled)
2016-09-26 07:41:57,255 process          L0435 INFO | Command 'systemctl status libvirtd.service' finished with 0 after 0.00542211532593s
2016-09-26 07:41:57,255 process          L0421 DEBUG| [stdout]    Active: active (running) since Mon 2016-09-26 07:41:56 AKDT; 728ms ago
2016-09-26 07:41:57,255 process          L0421 DEBUG| [stdout]      Docs: man:libvirtd(8)
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]            http://libvirt.org
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]  Main PID: 143933 (libvirtd)
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]    Memory: 33.9M
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]    CGroup: /system.slice/libvirtd.service
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]            |-  7783 /sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/default.conf --leasefile-ro --dhcp-script=/usr/libexec/libvirt_leaseshelper
2016-09-26 07:41:57,256 process          L0421 DEBUG| [stdout]            |-  7784 /sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/default.conf --leasefile-ro --dhcp-script=/usr/libexec/libvirt_leaseshelper
2016-09-26 07:41:57,766 utils_misc       L0607 DEBUG| waiting for domain virt-tests-vm1 to start (0.000013 secs)
2016-09-26 07:41:57,858 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,861 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,862 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000995874404907s
2016-09-26 07:41:57,862 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,862 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,862 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,862 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,863 process          L0421 DEBUG| [stdout]   8:  40  10
2016-09-26 07:41:57,864 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,867 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,867 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000855207443237s
2016-09-26 07:41:57,868 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,868 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,868 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,868 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,868 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,869 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,869 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,869 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,869 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,870 utils_misc       L1794 WARNI| Can not find the cpu list information from both numactl and sysfs. Please check your system.
2016-09-26 07:41:57,870 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,873 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,873 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000849962234497s
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,874 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,875 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,875 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,875 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,875 process          L0421 DEBUG| [stdout]   8:  40  10
2016-09-26 07:41:57,875 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,879 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,879 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000833034515381s
2016-09-26 07:41:57,879 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,879 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,879 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,880 process          L0421 DEBUG| [stdout]   8:  40  10
2016-09-26 07:41:57,881 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,884 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,884 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000823974609375s
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,885 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,886 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,886 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,886 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,886 process          L0421 DEBUG| [stdout]   8:  40  10
2016-09-26 07:41:57,886 process          L0334 INFO | Running 'numactl --hardware'
2016-09-26 07:41:57,889 process          L0421 DEBUG| [stdout] available: 2 nodes (0,8)
2016-09-26 07:41:57,890 process          L0435 INFO | Command 'numactl --hardware' finished with 0 after 0.000887870788574s
2016-09-26 07:41:57,890 process          L0421 DEBUG| [stdout] node 0 cpus: 0 8 16 24 32 40 48 56 64 72
2016-09-26 07:41:57,890 process          L0421 DEBUG| [stdout] node 0 size: 262144 MB
2016-09-26 07:41:57,890 process          L0421 DEBUG| [stdout] node 0 free: 253192 MB
2016-09-26 07:41:57,890 process          L0421 DEBUG| [stdout] node 8 cpus: 80 88 96 104 112 120 128 136 144 152
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout] node 8 size: 262144 MB
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout] node 8 free: 254550 MB
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout] node distances:
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout] node   0   8
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout]   0:  10  40
2016-09-26 07:41:57,891 process          L0421 DEBUG| [stdout]   8:  40  10
2016-09-26 07:41:57,892 virsh_numatune   L0115 DEBUG| host node list is [0, 8]
2016-09-26 07:41:57,892 virsh            L0645 DEBUG| Running virsh command: numatune virt-tests-vm1
2016-09-26 07:41:57,892 process          L0334 INFO | Running '/bin/virsh numatune virt-tests-vm1'
2016-09-26 07:41:57,907 process          L0421 DEBUG| [stdout] numa_mode      : strict
2016-09-26 07:41:57,907 process          L0421 DEBUG| [stdout] numa_nodeset   :
2016-09-26 07:41:57,907 process          L0421 DEBUG| [stdout]
2016-09-26 07:41:57,907 process          L0435 INFO | Command '/bin/virsh numatune virt-tests-vm1' finished with 0 after 0.0130209922791s
2016-09-26 07:41:57,908 virsh            L0691 DEBUG| status: 0
2016-09-26 07:41:57,908 virsh            L0692 DEBUG| stdout: numa_mode      : strict
numa_nodeset   :
2016-09-26 07:41:57,908 virsh            L0693 DEBUG| stderr:
2016-09-26 07:41:58,231 virsh            L1332 DEBUG| Undefine VM virt-tests-vm1
2016-09-26 07:41:58,248 virsh            L1316 DEBUG| Define VM from /var/tmp/xml_utils_temp_RvA2SG.xml
2016-09-26 07:41:58,265 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:58,265 process          L0334 INFO | Running 'systemctl start cgconfig.service'
2016-09-26 07:41:58,320 process          L0435 INFO | Command 'systemctl start cgconfig.service' finished with 0 after 0.0518870353699s
2016-09-26 07:41:58,321 process          L0334 INFO | Running 'true'
2016-09-26 07:41:58,324 process          L0435 INFO | Command 'true' finished with 0 after 0.000397920608521s
2016-09-26 07:41:58,324 process          L0334 INFO | Running 'ps -o comm 1'
2016-09-26 07:41:58,387 process          L0421 DEBUG| [stdout] COMMAND
2016-09-26 07:41:58,387 process          L0435 INFO | Command 'ps -o comm 1' finished with 0 after 0.0610570907593s
2016-09-26 07:41:58,388 process          L0421 DEBUG| [stdout] systemd
2016-09-26 07:41:58,388 utils_libvirtd   L0321 WARNI| This function was deprecated, Please use class utils_libvirtd.Libvirtd to manage libvirtd service.
2016-09-26 07:41:58,388 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:58,388 process          L0334 INFO | Running 'systemctl reset-failed libvirtd.service'
2016-09-26 07:41:58,394 process          L0435 INFO | Command 'systemctl reset-failed libvirtd.service' finished with 0 after 0.00322294235229s
2016-09-26 07:41:58,395 service          L0471 DEBUG| Setting ignore_status to True.
2016-09-26 07:41:58,395 process          L0334 INFO | Running 'systemctl restart libvirtd.service'
2016-09-26 07:41:58,437 process          L0435 INFO | Command 'systemctl restart libvirtd.service' finished with 0 after 0.0389850139618s
2016-09-26 07:41:58,437 process          L0334 INFO | Running 'virsh list'
2016-09-26 07:41:59,107 process          L0421 DEBUG| [stdout]  Id    Name                           State
2016-09-26 07:41:59,107 process          L0421 DEBUG| [stdout] ----------------------------------------------------
2016-09-26 07:41:59,108 process          L0421 DEBUG| [stdout]
2016-09-26 07:41:59,108 process          L0435 INFO | Command 'virsh list' finished with 0 after 0.667789936066s
2016-09-26 07:41:59,160 qemu_storage     L0480 DEBUG| Checking image file /var/lib/libvirt/images/workspace/runAvocadoFVTTest/avocado-fvt-wrapper/data/avocado-vt/images/f24-ppc64le.qcow2
2016-09-26 07:41:59,339 process          L0334 INFO | Running 'true'
2016-09-26 07:41:59,342 process          L0435 INFO | Command 'true' finished with 0 after 0.00040602684021s
2016-09-26 07:41:59,343 process          L0334 INFO | Running 'ps -o comm 1'
2016-09-26 07:41:59,108 process          L0421 DEBUG| [stdout]
2016-09-26 07:41:59,108 process          L0435 INFO | Command 'virsh list' finished with 0 after 0.667789936066s
2016-09-26 07:41:59,160 qemu_storage     L0480 DEBUG| Checking image file /var/lib/libvirt/images/workspace/runAvocadoFVTTest/avocado-fvt-wrapper/data/avocado-vt/images/f24-ppc64le.qcow2
2016-09-26 07:41:59,339 process          L0334 INFO | Running 'true'
2016-09-26 07:41:59,342 process          L0435 INFO | Command 'true' finished with 0 after 0.00040602684021s
2016-09-26 07:41:59,343 process          L0334 INFO | Running 'ps -o comm 1'
2016-09-26 07:41:59,395 process          L0421 DEBUG| [stdout] COMMAND
2016-09-26 07:41:59,396 process          L0435 INFO | Command 'ps -o comm 1' finished with 0 after 0.0502989292145s
2016-09-26 07:41:59,396 process          L0421 DEBUG| [stdout] systemd
2016-09-26 07:41:59,519 stacktrace       L0038 ERROR|
2016-09-26 07:41:59,519 stacktrace       L0041 ERROR| Reproduced traceback from: /usr/lib/python2.7/site-packages/avocado_plugins_vt-41.0-py2.7.egg/avocado_vt/test.py:420
2016-09-26 07:41:59,519 stacktrace       L0044 ERROR| Traceback (most recent call last):
2016-09-26 07:41:59,520 stacktrace       L0044 ERROR|   File "/usr/lib/python2.7/site-packages/avocado_plugins_vt-41.0-py2.7.egg/avocado_vt/test.py", line 206, in runTest
2016-09-26 07:41:59,520 stacktrace       L0044 ERROR|     raise exceptions.TestFail(details)
2016-09-26 07:41:59,520 stacktrace       L0044 ERROR| TestFail: Unexpected return code 0