No stdout with accel-kvm enabled
Posted: Sat Oct 14, 2017 11:49 am
Hi guys,
first of all thanks for your work on this amazing tool. Been a user for 2 years+ now, this is the first time I had a serious issue.
Symptoms: any qemu process starts properly, eats system resouces, but produces no output (none via telnet and guacamole/vnc as well). No errors are seen. Checked QEMU 2.4.0 and 2.9.0
The only thing that helps is to disable kvm by adding -no-kvm to qemu options.
Originally seen here https://gns3.com/qa/no-console-output-when-using-qem
Appeared after some system update in the end of September (I worked on 2.0.3-77 fine before)
***** EVE-NG INFORMATION TOOL - CREATED BY R! ******
Sat Oct 14 14:33:15 EEST 2017
***** EVE-NG VERSION INSTALLED *****
eve-ng 2.0.3-84
***** HOSTNAME INFORMATION *****
Static hostname: eve-ng
Virtualization: vmware > that's Workstation 14.0.0 build-6661328 running on Windows 10 [Version 10.0.15063]
Operating System: Ubuntu 16.04.3 LTS
Kernel: Linux 4.9.40-eve-ng-ukms-2+
Architecture: x86-64
***** FILE SYSTEM DISK SPACE USAGE *****
Filesystem Size Used Avail Use% Mounted on
udev 5.9G 0 5.9G 0% /dev
tmpfs 1.2G 14M 1.2G 2% /run
/dev/mapper/eve--ng--vg-root 33G 4.8G 27G 16% /
tmpfs 5.9G 0 5.9G 0% /dev/shm
tmpfs 5.0M 0 5.0M 0% /run/lock
tmpfs 5.9G 0 5.9G 0% /sys/fs/cgroup
/dev/sda1 472M 83M 366M 19% /boot
***** CPU INFORMATION *****
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
***** FREE AND USED MEMORY *****
total used free shared buff/cache available
Mem: 11G 5.4G 3.5G 23M 2.9G 5.9G
Swap: 6.0G 0B 6.0G
***** INTERFACE INFORMATION *****
pnet0 8000.000c29589c50 no eth0
pnet1 8000.000000000000 no
pnet2 8000.000000000000 no
pnet3 8000.000000000000 no
pnet4 8000.000000000000 no
pnet5 8000.000000000000 no
pnet6 8000.000000000000 no
pnet7 8000.000000000000 no
pnet8 8000.000000000000 no
pnet9 8000.000000000000 no
***** KVM CHECK INFORMATION *****
INFO: /dev/kvm exists
KVM acceleration can be used
***** APACHE STATUS *****
* apache2.service - LSB: Apache2 web server
Active: active (running) since Sat 2017-10-14 15:38:54 EEST; 1h 5min left
***** CHECKING INERNET STATUS *****
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data.
64 bytes from 8.8.8.8: icmp_seq=1 ttl=58 time=24.6 ms
--- 8.8.8.8 ping statistics ---
1 packets transmitted, 1 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 24.685/24.685/24.685/0.000 ms
***** COPY or SCREENSHOT THIS INFORMATION FOR EVE SUPPORT TO HELP/UNDERSTAND YOUR SYSTEM INFORMATION.******
***** http://www.eve-ng.net/forum/ ******
root@eve-ng:~# cat /proc/cpuinfo
processor : 0
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 0
siblings : 1
core id : 0
cpu cores : 1
apicid : 0
initial apicid : 0
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 1
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 2
siblings : 1
core id : 0
cpu cores : 1
apicid : 2
initial apicid : 2
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 2
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 4
siblings : 1
core id : 0
cpu cores : 1
apicid : 4
initial apicid : 4
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 3
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 6
siblings : 1
core id : 0
cpu cores : 1
apicid : 6
initial apicid : 6
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
Standard qemu launch:
root@eve-ng:~# cat /opt/unetlab/data/Logs/unl_wrapper.txt
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_0 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_0.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_1 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_1.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_2 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_2.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_3 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_3.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_4 2>&1
Oct 14 12:42:27 INFO: sysctl -w net.ipv6.conf.vunl0_1_4.disable_ipv6=1
Oct 14 12:42:27 INFO: tunctl -u unl0 -g root -t vunl0_1_5 2>&1
Oct 14 12:42:27 INFO: sysctl -w net.ipv6.conf.vunl0_1_5.disable_ipv6=1
Oct 14 12:42:27 ERROR: QEMU Arch is not set (80015).
Oct 14 12:42:27 INFO: CWD is /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1
Oct 14 12:42:27 INFO: starting /opt/unetlab/wrappers/qemu_wrapper -T 0 -D 1 -t "vMX" -F /opt/qemu/bin/qemu-system-x86_64 -d 0 -- -nographic -device virtio-net-pci,netdev=net0,mac=50:00:00:01:00:00 -netdev tap,id=net0,ifname=vunl0_1_0,script=no -device virtio-net-pci,netdev=net1,mac=50:00:00:01:00:01 -netdev tap,id=net1,ifname=vunl0_1_1,script=no -device virtio-net-pci,netdev=net2,mac=50:00:00:01:00:02 -netdev tap,id=net2,ifname=vunl0_1_2,script=no -device virtio-net-pci,netdev=net3,mac=50:00:00:01:00:03 -netdev tap,id=net3,ifname=vunl0_1_3,script=no -device virtio-net-pci,netdev=net4,mac=50:00:00:01:00:04 -netdev tap,id=net4,ifname=vunl0_1_4,script=no -device virtio-net-pci,netdev=net5,mac=50:00:00:01:00:05 -netdev tap,id=net5,ifname=vunl0_1_5,script=no -smp 1 -m 2048 -name vMX -uuid 66931d96-5c59-488c-9236-4212c3918e6a -hda hda.qcow2 -machine type=pc-1.0,accel=kvm -serial mon:stdio -nographic > /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1/wrapper.txt 2>&1 &
Oct 14 12:46:05 INFO: stopping fuser -k -TERM /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1 > /dev/null 2>&1
first of all thanks for your work on this amazing tool. Been a user for 2 years+ now, this is the first time I had a serious issue.
Symptoms: any qemu process starts properly, eats system resouces, but produces no output (none via telnet and guacamole/vnc as well). No errors are seen. Checked QEMU 2.4.0 and 2.9.0
The only thing that helps is to disable kvm by adding -no-kvm to qemu options.
Originally seen here https://gns3.com/qa/no-console-output-when-using-qem
Appeared after some system update in the end of September (I worked on 2.0.3-77 fine before)
***** EVE-NG INFORMATION TOOL - CREATED BY R! ******
Sat Oct 14 14:33:15 EEST 2017
***** EVE-NG VERSION INSTALLED *****
eve-ng 2.0.3-84
***** HOSTNAME INFORMATION *****
Static hostname: eve-ng
Virtualization: vmware > that's Workstation 14.0.0 build-6661328 running on Windows 10 [Version 10.0.15063]
Operating System: Ubuntu 16.04.3 LTS
Kernel: Linux 4.9.40-eve-ng-ukms-2+
Architecture: x86-64
***** FILE SYSTEM DISK SPACE USAGE *****
Filesystem Size Used Avail Use% Mounted on
udev 5.9G 0 5.9G 0% /dev
tmpfs 1.2G 14M 1.2G 2% /run
/dev/mapper/eve--ng--vg-root 33G 4.8G 27G 16% /
tmpfs 5.9G 0 5.9G 0% /dev/shm
tmpfs 5.0M 0 5.0M 0% /run/lock
tmpfs 5.9G 0 5.9G 0% /sys/fs/cgroup
/dev/sda1 472M 83M 366M 19% /boot
***** CPU INFORMATION *****
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
***** FREE AND USED MEMORY *****
total used free shared buff/cache available
Mem: 11G 5.4G 3.5G 23M 2.9G 5.9G
Swap: 6.0G 0B 6.0G
***** INTERFACE INFORMATION *****
pnet0 8000.000c29589c50 no eth0
pnet1 8000.000000000000 no
pnet2 8000.000000000000 no
pnet3 8000.000000000000 no
pnet4 8000.000000000000 no
pnet5 8000.000000000000 no
pnet6 8000.000000000000 no
pnet7 8000.000000000000 no
pnet8 8000.000000000000 no
pnet9 8000.000000000000 no
***** KVM CHECK INFORMATION *****
INFO: /dev/kvm exists
KVM acceleration can be used
***** APACHE STATUS *****
* apache2.service - LSB: Apache2 web server
Active: active (running) since Sat 2017-10-14 15:38:54 EEST; 1h 5min left
***** CHECKING INERNET STATUS *****
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data.
64 bytes from 8.8.8.8: icmp_seq=1 ttl=58 time=24.6 ms
--- 8.8.8.8 ping statistics ---
1 packets transmitted, 1 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 24.685/24.685/24.685/0.000 ms
***** COPY or SCREENSHOT THIS INFORMATION FOR EVE SUPPORT TO HELP/UNDERSTAND YOUR SYSTEM INFORMATION.******
***** http://www.eve-ng.net/forum/ ******
root@eve-ng:~# cat /proc/cpuinfo
processor : 0
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 0
siblings : 1
core id : 0
cpu cores : 1
apicid : 0
initial apicid : 0
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 1
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 2
siblings : 1
core id : 0
cpu cores : 1
apicid : 2
initial apicid : 2
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 2
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 4
siblings : 1
core id : 0
cpu cores : 1
apicid : 4
initial apicid : 4
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
processor : 3
vendor_id : GenuineIntel
cpu family : 6
model : 94
model name : Intel(R) Core(TM) i7-6700K CPU @ 4.00GHz
stepping : 3
microcode : 0xa0
cpu MHz : 4008.016
cache size : 8192 KB
physical id : 6
siblings : 1
core id : 0
cpu cores : 1
apicid : 6
initial apicid : 6
fpu : yes
fpu_exception : yes
cpuid level : 22
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ss syscall nx pdpe1gb rdtscp lm constant_tsc arch_perfmon nopl xtopology tsc_reliable nonstop_tsc eagerfpu pni pclmulqdq vmx ssse3 fma cx16 pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand hypervisor lahf_lm abm 3dnowprefetch tpr_shadow vnmi ept vpid fsgsbase tsc_adjust bmi1 hle avx2 smep bmi2 invpcid rtm rdseed adx smap xsaveopt arat
bugs :
bogomips : 8016.01
clflush size : 64
cache_alignment : 64
address sizes : 42 bits physical, 48 bits virtual
power management:
Standard qemu launch:
root@eve-ng:~# cat /opt/unetlab/data/Logs/unl_wrapper.txt
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_0 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_0.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_1 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_1.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_2 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_2.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_3 2>&1
Oct 14 12:42:26 INFO: sysctl -w net.ipv6.conf.vunl0_1_3.disable_ipv6=1
Oct 14 12:42:26 INFO: tunctl -u unl0 -g root -t vunl0_1_4 2>&1
Oct 14 12:42:27 INFO: sysctl -w net.ipv6.conf.vunl0_1_4.disable_ipv6=1
Oct 14 12:42:27 INFO: tunctl -u unl0 -g root -t vunl0_1_5 2>&1
Oct 14 12:42:27 INFO: sysctl -w net.ipv6.conf.vunl0_1_5.disable_ipv6=1
Oct 14 12:42:27 ERROR: QEMU Arch is not set (80015).
Oct 14 12:42:27 INFO: CWD is /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1
Oct 14 12:42:27 INFO: starting /opt/unetlab/wrappers/qemu_wrapper -T 0 -D 1 -t "vMX" -F /opt/qemu/bin/qemu-system-x86_64 -d 0 -- -nographic -device virtio-net-pci,netdev=net0,mac=50:00:00:01:00:00 -netdev tap,id=net0,ifname=vunl0_1_0,script=no -device virtio-net-pci,netdev=net1,mac=50:00:00:01:00:01 -netdev tap,id=net1,ifname=vunl0_1_1,script=no -device virtio-net-pci,netdev=net2,mac=50:00:00:01:00:02 -netdev tap,id=net2,ifname=vunl0_1_2,script=no -device virtio-net-pci,netdev=net3,mac=50:00:00:01:00:03 -netdev tap,id=net3,ifname=vunl0_1_3,script=no -device virtio-net-pci,netdev=net4,mac=50:00:00:01:00:04 -netdev tap,id=net4,ifname=vunl0_1_4,script=no -device virtio-net-pci,netdev=net5,mac=50:00:00:01:00:05 -netdev tap,id=net5,ifname=vunl0_1_5,script=no -smp 1 -m 2048 -name vMX -uuid 66931d96-5c59-488c-9236-4212c3918e6a -hda hda.qcow2 -machine type=pc-1.0,accel=kvm -serial mon:stdio -nographic > /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1/wrapper.txt 2>&1 &
Oct 14 12:46:05 INFO: stopping fuser -k -TERM /opt/unetlab/tmp/0/262e4d0e-ec23-471e-99f0-7a8c7fc68f28/1 > /dev/null 2>&1