Discussion:
time runs at 50% inside a vmm host
Paul de Weerd
2017-05-12 11:00:22 UTC
Permalink
I've just started using VMM to do some testing and found this issue:

[***@vm1] $ N=`awk '/^server/ {print $2}' /etc/ntpd.conf | head -1`
[***@vm1] $ rdate -npv ${N}; time sleep 10; rdate -npv ${N}
Fri May 12 11:41:07 CEST 2017
rdate: adjust local clock by 74.831786 seconds
0m10.05s real 0m00.00s user 0m00.00s system
Fri May 12 11:41:27 CEST 2017
rdate: adjust local clock by 84.639531 seconds

So for every second of wall-clock time that passes on my VM, two
pass on the host (and on the internet). Of course, ntpd is unable to
correct this kind of deviation. Is this a misconfiguration on my end
or a bug?

Cheers,

Paul 'WEiRD' de Weerd

[***@pom] $ cat /etc/vm.conf
vm "vm1" {
disable
interface { switch "uplink" }
disk /store/vmd/vm1.img
memory 1G
owner weerd
}

switch "uplink" {
add em0
}

VM dmesg:

OpenBSD 6.1-current (GENERIC) #50: Wed May 10 18:57:14 MDT 2017
***@amd64.openbsd.org:/usr/src/sys/arch/amd64/compile/GENERIC
real mem = 1056956416 (1007MB)
avail mem = 1019187200 (971MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0
acpi at bios0 not configured
cpu0 at mainbus0: (uniprocessor)
cpu0: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.57 MHz
cpu0: FPU,VME,DE,PSE,MSR,PAE,MCE,CX8,SEP,PGE,MCA,CMOV,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,SSE3,PCLMUL,SSSE3,FMA3,CX16,SSE4.1,SSE4.2,MOVBE,POPCNT,AES,XSAVE,F16C,RDRAND,HV,NXE,PAGE1GB,LONG,LAHF,ABM,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS
cpu0: 256KB 64b/line 8-way L2 cache
cpu0: smt 0, core 0, package 0
pvbus0 at mainbus0: OpenBSD
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "OpenBSD VMM Host" rev 0x00
virtio0 at pci0 dev 1 function 0 "Qumranet Virtio RNG" rev 0x00
viornd0 at virtio0
virtio0: irq 3
virtio1 at pci0 dev 2 function 0 "Qumranet Virtio Storage" rev 0x00
vioblk0 at virtio1
scsibus1 at vioblk0: 2 targets
sd0 at scsibus1 targ 0 lun 0: <VirtIO, Block Device, > SCSI3 0/direct fixed
sd0: 10240MB, 512 bytes/sector, 20971520 sectors
virtio1: irq 5
virtio2 at pci0 dev 3 function 0 "Qumranet Virtio Network" rev 0x00
vio0 at virtio2: address fe:e1:bb:d1:0e:24
virtio2: irq 7
virtio3 at pci0 dev 4 function 0 "OpenBSD VMM Control" rev 0x00
vmmci0 at virtio3
virtio3: irq 9
isa0 at mainbus0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns8250, no fifo
com0: console
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
root on sd0a (1f0612abbcd082c8.a) swap on sd0b dump on sd0b

Host dmesg:

OpenBSD 6.1-current (GENERIC.MP) #53: Wed May 10 19:00:28 MDT 2017
***@amd64.openbsd.org:/usr/src/sys/arch/amd64/compile/GENERIC.MP
real mem = 34243919872 (32657MB)
avail mem = 33200308224 (31662MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.7 @ 0xec410 (88 entries)
bios0: vendor Dell Inc. version "A12" date 05/06/2015
bios0: Dell Inc. OptiPlex 9020
acpi0 at bios0: rev 2
acpi0: sleep states S0 S3 S4 S5
acpi0: tables DSDT FACP APIC FPDT SLIC LPIT SSDT SSDT SSDT HPET SSDT MCFG SSDT ASF! DMAR
acpi0: wakeup devices UAR1(S3) PXSX(S4) PXSX(S4) PXSX(S4) PXSX(S4) PXSX(S4) PXSX(S4) PXSX(S4) GLAN(S4) EHC1(S3) EHC2(S3) XHC_(S4) HDEF(S4) PEG0(S4) PEGP(S4) PEG1(S4) [...]
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.74 MHz
cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu0: 256KB 64b/line 8-way L2 cache
cpu0: TSC frequency 3392739810 Hz
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 10 var ranges, 88 fixed ranges
cpu0: apic clock running at 99MHz
cpu0: mwait min=64, max=64, C-substates=0.2.1.2.4, IBE
cpu1 at mainbus0: apid 2 (application processor)
cpu1: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu1: 256KB 64b/line 8-way L2 cache
cpu1: smt 0, core 1, package 0
cpu2 at mainbus0: apid 4 (application processor)
cpu2: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu2: 256KB 64b/line 8-way L2 cache
cpu2: smt 0, core 2, package 0
cpu3 at mainbus0: apid 6 (application processor)
cpu3: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu3: 256KB 64b/line 8-way L2 cache
cpu3: smt 0, core 3, package 0
cpu4 at mainbus0: apid 1 (application processor)
cpu4: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu4: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu4: 256KB 64b/line 8-way L2 cache
cpu4: smt 1, core 0, package 0
cpu5 at mainbus0: apid 3 (application processor)
cpu5: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu5: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu5: 256KB 64b/line 8-way L2 cache
cpu5: smt 1, core 1, package 0
cpu6 at mainbus0: apid 5 (application processor)
cpu6: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu6: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu6: 256KB 64b/line 8-way L2 cache
cpu6: smt 1, core 2, package 0
cpu7 at mainbus0: apid 7 (application processor)
cpu7: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz, 3392.15 MHz
cpu7: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,SDBG,FMA3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,DEADLINE,AES,XSAVE,AVX,F16C,RDRAND,NXE,PAGE1GB,RDTSCP,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,BMI1,AVX2,SMEP,BMI2,ERMS,INVPCID,SENSOR,ARAT
cpu7: 256KB 64b/line 8-way L2 cache
cpu7: smt 1, core 3, package 0
ioapic0 at mainbus0: apid 8 pa 0xfec00000, version 20, 24 pins
acpihpet0 at acpi0: 14318179 Hz
acpimcfg0 at acpi0 addr 0xf8000000, bus 0-63
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus -1 (PEG0)
acpiprt2 at acpi0: bus -1 (PEG1)
acpiprt3 at acpi0: bus -1 (PEG2)
acpiec0 at acpi0: not present
acpicpu0 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu1 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu2 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu3 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu4 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu5 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu6 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpicpu7 at acpi0: C2(***@148 ***@0x33), C1(***@1 mwait.1), PSS
acpitz0 at acpi0: critical temperature is 105 degC
acpitz1 at acpi0: critical temperature is 105 degC
"INT3F0D" at acpi0 not configured
"PNP0501" at acpi0 not configured
acpibtn0 at acpi0: PWRB
acpivideo0 at acpi0: GFX0
acpivout0 at acpivideo0: DD1F
cpu0: Enhanced SpeedStep 3392 MHz: speeds: 3401, 3400, 3200, 3000, 2800, 2700, 2500, 2300, 2100, 1900, 1700, 1500, 1400, 1200, 1000, 800 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel Core 4G Host" rev 0x06
inteldrm0 at pci0 dev 2 function 0 "Intel HD Graphics 4600" rev 0x06
drm0 at inteldrm0
inteldrm0: msi
inteldrm0: 1920x1080, 32bpp
wsdisplay0 at inteldrm0 mux 1: console (std, vt100 emulation)
wsdisplay0: screen 1-5 added (std, vt100 emulation)
azalia0 at pci0 dev 3 function 0 "Intel Core 4G HD Audio" rev 0x06: msi
azalia0: No codecs found
xhci0 at pci0 dev 20 function 0 "Intel 8 Series xHCI" rev 0x04: msi
usb0 at xhci0: USB revision 3.0
uhub0 at usb0 configuration 1 interface 0 "Intel xHCI root hub" rev 3.00/1.00 addr 1
"Intel 8 Series MEI" rev 0x04 at pci0 dev 22 function 0 not configured
puc0 at pci0 dev 22 function 3 "Intel 8 Series KT" rev 0x04: ports: 1 com
com4 at puc0 port 0 apic 8 int 19: ns16550a, 16 byte fifo
com4: probed fifo depth: 0 bytes
em0 at pci0 dev 25 function 0 "Intel I217-LM" rev 0x04: msi, address b8:ca:3a:93:03:e8
ehci0 at pci0 dev 26 function 0 "Intel 8 Series USB" rev 0x04: apic 8 int 16
usb1 at ehci0: USB revision 2.0
uhub1 at usb1 configuration 1 interface 0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
azalia1 at pci0 dev 27 function 0 "Intel 8 Series HD Audio" rev 0x04: msi
azalia1: codecs: Realtek/0x0280
audio0 at azalia1
ehci1 at pci0 dev 29 function 0 "Intel 8 Series USB" rev 0x04: apic 8 int 23
usb2 at ehci1: USB revision 2.0
uhub2 at usb2 configuration 1 interface 0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
pcib0 at pci0 dev 31 function 0 "Intel Q87 LPC" rev 0x04
ahci0 at pci0 dev 31 function 2 "Intel 8 Series AHCI" rev 0x04: msi, AHCI 1.3
ahci0: port 0: 6.0Gb/s
ahci0: port 1: 1.5Gb/s
ahci0: port 2: 6.0Gb/s
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 0 lun 0: <ATA, Samsung SSD 850, EMT0> SCSI3 0/direct fixed naa.5002538d41b86e4d
sd0: 953869MB, 512 bytes/sector, 1953525168 sectors, thin
cd0 at scsibus1 targ 1 lun 0: <HL-DT-ST, DVD+-RW GT80N, A103> ATAPI 5/cdrom removable
sd1 at scsibus1 targ 2 lun 0: <ATA, WDC WD60EFRX-68L, 82.0> SCSI3 0/direct fixed naa.50014ee2b81f4111
sd1: 5723166MB, 512 bytes/sector, 11721045168 sectors
ichiic0 at pci0 dev 31 function 3 "Intel 8 Series SMBus" rev 0x04: apic 8 int 18
iic0 at ichiic0
spdmem0 at iic0 addr 0x50: 8GB DDR3 SDRAM PC3-12800
spdmem1 at iic0 addr 0x51: 8GB DDR3 SDRAM PC3-12800
spdmem2 at iic0 addr 0x52: 8GB DDR3 SDRAM PC3-12800
spdmem3 at iic0 addr 0x53: 8GB DDR3 SDRAM PC3-12800
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
pckbc0 at isa0 port 0x60/5 irq 1 irq 12
pckbd0 at pckbc0 (kbd slot)
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
vmm0 at mainbus0: VMX/EPT
uhub3 at uhub0 port 20 configuration 1 interface 0 "VIA Labs, Inc. USB3.0 Hub" rev 3.00/90.91 addr 2
uhub4 at uhub3 port 1 configuration 1 interface 0 "VIA Labs, Inc. USB3.0 Hub" rev 3.00/90.91 addr 3
umass0 at uhub4 port 1 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.19 addr 4
umass0: using SCSI over Bulk-Only
scsibus2 at umass0: 2 targets, initiator 0
sd2 at scsibus2 targ 1 lun 0: <WD, My Passport 0748, 1019> SCSI4 0/direct fixed
sd2: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses0 at scsibus2 targ 1 lun 1: <WD, SES Device, 1019> SCSI4 13/enclosure services fixed
ses0: unable to read enclosure configuration
umass1 at uhub4 port 2 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.10 addr 5
umass1: using SCSI over Bulk-Only
scsibus3 at umass1: 2 targets, initiator 0
sd3 at scsibus3 targ 1 lun 0: <WD, My Passport 0748, 1010> SCSI4 0/direct fixed
sd3: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses1 at scsibus3 targ 1 lun 1: <WD, SES Device, 1010> SCSI4 13/enclosure services fixed
ses1: unable to read enclosure configuration
umass2 at uhub4 port 3 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.10 addr 6
umass2: using SCSI over Bulk-Only
scsibus4 at umass2: 2 targets, initiator 0
sd4 at scsibus4 targ 1 lun 0: <WD, My Passport 0748, 1010> SCSI4 0/direct fixed
sd4: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses2 at scsibus4 targ 1 lun 1: <WD, SES Device, 1010> SCSI4 13/enclosure services fixed
ses2: unable to read enclosure configuration
umass3 at uhub4 port 4 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.10 addr 7
umass3: using SCSI over Bulk-Only
scsibus5 at umass3: 2 targets, initiator 0
sd5 at scsibus5 targ 1 lun 0: <WD, My Passport 0748, 1010> SCSI4 0/direct fixed
sd5: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses3 at scsibus5 targ 1 lun 1: <WD, SES Device, 1010> SCSI4 13/enclosure services fixed
ses3: unable to read enclosure configuration
umass4 at uhub3 port 2 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.19 addr 8
umass4: using SCSI over Bulk-Only
scsibus6 at umass4: 2 targets, initiator 0
sd6 at scsibus6 targ 1 lun 0: <WD, My Passport 0748, 1019> SCSI4 0/direct fixed
sd6: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses4 at scsibus6 targ 1 lun 1: <WD, SES Device, 1019> SCSI4 13/enclosure services fixed
ses4: unable to read enclosure configuration
umass5 at uhub3 port 3 configuration 1 interface 0 "Western Digital My Passport 0748" rev 3.00/10.19 addr 9
umass5: using SCSI over Bulk-Only
scsibus7 at umass5: 2 targets, initiator 0
sd7 at scsibus7 targ 1 lun 0: <WD, My Passport 0748, 1019> SCSI4 0/direct fixed
sd7: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses5 at scsibus7 targ 1 lun 1: <WD, SES Device, 1019> SCSI4 13/enclosure services fixed
ses5: unable to read enclosure configuration
uhub5 at uhub1 port 1 configuration 1 interface 0 "Intel Rate Matching Hub" rev 2.00/0.04 addr 2
uhidev0 at uhub5 port 1 configuration 1 interface 0 "Logitech Logitech Illuminated Keyboard" rev 2.00/55.01 addr 3
uhidev0: iclass 3/1
ukbd0 at uhidev0: 8 variable keys, 6 key codes
wskbd1 at ukbd0 mux 1
wskbd1: connecting to wsdisplay0
uhidev1 at uhub5 port 1 configuration 1 interface 1 "Logitech Logitech Illuminated Keyboard" rev 2.00/55.01 addr 3
uhidev1: iclass 3/0, 16 report ids
uhid0 at uhidev1 reportid 3: input=7, output=0, feature=0
uhid1 at uhidev1 reportid 16: input=6, output=6, feature=0
uhidev2 at uhub5 port 2 configuration 1 interface 0 "Logitech USB Gaming Mouse" rev 2.00/46.00 addr 4
uhidev2: iclass 3/1
ums0 at uhidev2: 16 buttons, Z and W dir
wsmouse0 at ums0 mux 0
uhidev3 at uhub5 port 2 configuration 1 interface 1 "Logitech USB Gaming Mouse" rev 2.00/46.00 addr 4
uhidev3: iclass 3/0, 17 report ids
uhid2 at uhidev3 reportid 16: input=6, output=6, feature=0
uhid3 at uhidev3 reportid 17: input=19, output=19, feature=0
uhidev4 at uhub5 port 3 configuration 1 interface 0 "RDing TEMPERHUM1V1.2" rev 2.00/0.01 addr 5
uhidev4: iclass 3/1, 1 report id
ukbd1 at uhidev4 reportid 1: 8 variable keys, 5 key codes
wskbd2 at ukbd1 mux 1
wskbd2: connecting to wsdisplay0
uhidev5 at uhub5 port 3 configuration 1 interface 1 "RDing TEMPERHUM1V1.2" rev 2.00/0.01 addr 5
uhidev5: iclass 3/1
ugold0 at uhidev5
uslcom0 at uhub5 port 4 configuration 1 interface 0 "Silicon Labs Dynastream ANT2USB" rev 1.10/3.00 addr 6
ucom0 at uslcom0 portno 0
ugen0 at uhub5 port 6 "Apple Inc. iPhone" rev 2.00/5.20 addr 7
uhub6 at uhub2 port 1 configuration 1 interface 0 "Intel Rate Matching Hub" rev 2.00/0.04 addr 2
uhub7 at uhub6 port 3 configuration 1 interface 0 "VIA Labs, Inc. USB2.0 Hub" rev 2.10/90.90 addr 3
uhub8 at uhub7 port 1 configuration 1 interface 0 "VIA Labs, Inc. USB2.0 Hub" rev 2.10/90.90 addr 4
uvideo0 at uhub6 port 4 configuration 1 interface 0 "Logitech QuickCam Pro 9000" rev 2.00/0.08 addr 5
video0 at uvideo0
uaudio0 at uhub6 port 4 configuration 1 interface 2 "Logitech QuickCam Pro 9000" rev 2.00/0.08 addr 5
uaudio0: audio rev 1.00, 2 mixer controls
audio1 at uaudio0
ugold0: 2 sensors type si7006 (temperature and humidity)
uhub9 at uhub6 port 6 configuration 1 interface 0 "VIA Labs, Inc. USB2.0 Hub" rev 2.10/90.90 addr 6
uhub10 at uhub9 port 1 configuration 1 interface 0 "VIA Labs, Inc. USB2.0 Hub" rev 2.10/90.90 addr 7
umass6 at uhub10 port 1 configuration 1 interface 0 "Western Digital My Passport 0748" rev 2.10/10.19 addr 8
umass6: using SCSI over Bulk-Only
scsibus8 at umass6: 2 targets, initiator 0
sd8 at scsibus8 targ 1 lun 0: <WD, My Passport 0748, 1019> SCSI4 0/direct fixed
sd8: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses6 at scsibus8 targ 1 lun 1: <WD, SES Device, 1019> SCSI4 13/enclosure services fixed
ses6: unable to read enclosure configuration
umass7 at uhub10 port 2 configuration 1 interface 0 "Western Digital My Passport 0748" rev 2.10/10.19 addr 9
umass7: using SCSI over Bulk-Only
scsibus9 at umass7: 2 targets, initiator 0
sd9 at scsibus9 targ 1 lun 0: <WD, My Passport 0748, 1019> SCSI4 0/direct fixed
sd9: 1907697MB, 512 bytes/sector, 3906963456 sectors
ses7 at scsibus9 targ 1 lun 1: <WD, SES Device, 1019> SCSI4 13/enclosure services fixed
ses7: unable to read enclosure configuration
umass8 at uhub10 port 3 configuration 1 interface 0 "Western Digital My Passport 0730" rev 2.10/10.08 addr 10
umass8: using SCSI over Bulk-Only
scsibus10 at umass8: 2 targets, initiator 0
sd10 at scsibus10 targ 1 lun 0: <WD, My Passport 0730, 1008> SCSI4 0/direct fixed
sd10: 953837MB, 512 bytes/sector, 1953458176 sectors
ses8 at scsibus10 targ 1 lun 1: <WD, SES Device, 1008> SCSI4 13/enclosure services fixed
ses8: unable to read enclosure configuration
umass9 at uhub10 port 4 configuration 1 interface 0 "Western Digital Elements 10B8" rev 2.10/10.12 addr 11
umass9: using SCSI over Bulk-Only
scsibus11 at umass9: 2 targets, initiator 0
sd11 at scsibus11 targ 1 lun 0: <WD, Elements 10B8, 1012> SCSI4 0/direct fixed serial.105810b835354C4C3435
sd11: 1907697MB, 512 bytes/sector, 3906963456 sectors
umass10 at uhub9 port 2 configuration 1 interface 0 "Western Digital My Passport 259D" rev 2.10/10.12 addr 12
umass10: using SCSI over Bulk-Only
scsibus12 at umass10: 2 targets, initiator 0
sd12 at scsibus12 targ 1 lun 0: <WD, My Passport 259D, 1012> SCSI4 0/direct fixed
sd12: 3815415MB, 512 bytes/sector, 7813969920 sectors
ses9 at scsibus12 targ 1 lun 1: <WD, SES Device, 1012> SCSI4 13/enclosure services fixed
ses9: unable to read enclosure configuration
umass11 at uhub9 port 3 configuration 1 interface 0 "Western Digital My Passport 259D" rev 2.10/10.12 addr 13
umass11: using SCSI over Bulk-Only
scsibus13 at umass11: 2 targets, initiator 0
sd13 at scsibus13 targ 1 lun 0: <WD, My Passport 259D, 1012> SCSI4 0/direct fixed
sd13: 3815415MB, 512 bytes/sector, 7813969920 sectors
ses10 at scsibus13 targ 1 lun 1: <WD, SES Device, 1012> SCSI4 13/enclosure services fixed
ses10: unable to read enclosure configuration
vscsi0 at root
scsibus14 at vscsi0: 256 targets
softraid0 at root
scsibus15 at softraid0: 256 targets
softraid0: sd13 was not shutdown properly
sd14 at scsibus15 targ 1 lun 0: <OPENBSD, SR CRYPTO, 006> SCSI2 0/direct fixed
sd14: 953866MB, 512 bytes/sector, 1953519473 sectors
softraid0: volume sd14 is roaming, it used to be sd13, updating metadata
root on sd14a (5c0d9a38cc895a7d.a) swap on sd14b dump on sd14b
--
++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
http://www.weirdnet.nl/
Alexander Bluhm
2017-05-12 13:54:12 UTC
Permalink
Is this a misconfiguration on my end or a bug?
There is an easy way to detect this, run a loop in the guest system:

***@q71:~# while sleep 1; do date; done
Fri May 12 06:27:20 CEST 2017
Fri May 12 06:27:22 CEST 2017
Fri May 12 06:27:24 CEST 2017

I see it on a fully emulated amd64 qemu. As a workaround I compile
my guest kernel with option HZ=50.

Problem is that the host clock and the guest clock a ticking
with exactly the same frequency. Then interrupts are lost.
This has been discussed with mlarkin@, he knows more details.

I am not aware of a fix.

bluhm
Paul de Weerd
2017-05-12 14:35:40 UTC
Permalink
Hi Alexander,

On Fri, May 12, 2017 at 03:54:12PM +0200, Alexander Bluhm wrote:
| On Fri, May 12, 2017 at 01:00:22PM +0200, Paul de Weerd wrote:
| > Is this a misconfiguration on my end or a bug?
|
| There is an easy way to detect this, run a loop in the guest system:
|
| ***@q71:~# while sleep 1; do date; done
| Fri May 12 06:27:20 CEST 2017
| Fri May 12 06:27:22 CEST 2017
| Fri May 12 06:27:24 CEST 2017
|
| I see it on a fully emulated amd64 qemu. As a workaround I compile
| my guest kernel with option HZ=50.

Thanks for your reply. Yours and an off-list reply both pointed at
the HZ parameter. I built a kernel with HZ=1000, and things now work
much better in the vm.

| Problem is that the host clock and the guest clock a ticking
| with exactly the same frequency. Then interrupts are lost.
| This has been discussed with mlarkin@, he knows more details.
|
| I am not aware of a fix.

If not a fix, this is a fine workaround.

Thanks to all involved in the development of vmm, it's really useful
for testing lots of things!

Cheers,

Paul 'WEiRD' de Weerd
--
++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
http://www.weirdnet.nl/
Paul de Weerd
2017-05-12 15:08:50 UTC
Permalink
On Fri, May 12, 2017 at 04:35:40PM +0200, Paul de Weerd wrote:
| Thanks for your reply. Yours and an off-list reply both pointed at
| the HZ parameter. I built a kernel with HZ=1000, and things now work
| much better in the vm.

I spoke too soon :-(

Things do work better, but the clock is still slow:

[***@vm1] $ rdate -npv ${N}; uptime
Fri May 12 16:50:14 CEST 2017
rdate: adjust local clock by 124.798956 seconds
4:48PM up 16 mins, 1 user, load averages: 0.00, 0.00, 0.00

So, in 16 minutes, the clock is 2 minutes behind. So, a lot better,
but still falling behind.

[***@vm1] $ rdate -npv ${N}; time sleep 600; rdate -npv ${N}
Fri May 12 16:51:47 CEST 2017
rdate: adjust local clock by 135.359214 seconds
10m03.01s real 0m00.00s user 0m00.00s system
Fri May 12 17:03:08 CEST 2017
rdate: adjust local clock by 213.572802 seconds

Which turns out to be almost 8 seconds per minute.

Paul 'WEiRD' de Weerd
--
++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
http://www.weirdnet.nl/
Mike Larkin
2017-05-12 17:28:35 UTC
Permalink
Post by Paul de Weerd
| Thanks for your reply. Yours and an off-list reply both pointed at
| the HZ parameter. I built a kernel with HZ=1000, and things now work
| much better in the vm.
I spoke too soon :-(
Fri May 12 16:50:14 CEST 2017
rdate: adjust local clock by 124.798956 seconds
4:48PM up 16 mins, 1 user, load averages: 0.00, 0.00, 0.00
So, in 16 minutes, the clock is 2 minutes behind. So, a lot better,
but still falling behind.
Fri May 12 16:51:47 CEST 2017
rdate: adjust local clock by 135.359214 seconds
10m03.01s real 0m00.00s user 0m00.00s system
Fri May 12 17:03:08 CEST 2017
rdate: adjust local clock by 213.572802 seconds
Which turns out to be almost 8 seconds per minute.
Paul 'WEiRD' de Weerd
--
++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+
+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
http://www.weirdnet.nl/
Known issue.

Loading...