Looks like it will take about 40 seconds to run on Azure Standard-D48-v3, bump the timeout threshold to 60 or 90 should be able to solve this bug.
06:48:28 INFO | START ubuntu_kvm_unit_tests.apic ubuntu_kvm_unit_tests.apic timestamp=1602571708 timeout=1800 localtime=Oct 13 06:48:28 06:48:28 DEBUG| Persistent state client._record_indent now set to 2 06:48:28 DEBUG| Persistent state client.unexpected_reboot now set to ('ubuntu_kvm_unit_tests.apic', 'ubuntu_kvm_unit_tests.apic') 06:48:28 DEBUG| Waiting for pid 20233 for 1800 seconds 06:48:28 DEBUG| Running 'kvm-ok' 06:48:28 DEBUG| [stdout] INFO: /dev/kvm exists 06:48:28 DEBUG| [stdout] KVM acceleration can be used 06:48:28 DEBUG| Running '/home/azure/autotest/client/tmp/ubuntu_kvm_unit_tests/src/kvm-unit-tests/tests/apic' 06:48:28 DEBUG| [stdout] BUILD_HEAD=4671e4ba 06:48:28 DEBUG| [stdout] timeout -k 1s --foreground 60 /usr/bin/qemu-system-x86_64 -nodefaults -device pc-testdev -device isa-debug-exit,iobase=0xf4,iosize=0x4 -vnc none -serial stdio -device pci-testdev -machine accel=kvm -kernel /tmp/tmp.kHcsacTcCc -smp 2 -cpu qemu64,+x2apic,+tsc-deadline # -initrd /tmp/tmp.Mc6ec4A6zG 06:48:28 DEBUG| [stderr] qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:ECX.svm [bit 2] 06:48:28 DEBUG| [stderr] qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H:ECX.svm [bit 2] 06:48:29 DEBUG| [stdout] enabling apic 06:48:29 DEBUG| [stdout] enabling apic 06:48:29 DEBUG| [stdout] paging enabled 06:48:29 DEBUG| [stdout] cr0 = 80010011 06:48:29 DEBUG| [stdout] cr3 = 45a000 06:48:29 DEBUG| [stdout] cr4 = 20 06:48:29 DEBUG| [stdout] apic version: 50014 06:48:29 DEBUG| [stdout] PASS: apic existence 06:48:29 DEBUG| [stdout] PASS: xapic id matches cpuid 06:48:29 DEBUG| [stdout] PASS: writeable xapic id 06:48:29 DEBUG| [stdout] PASS: non-writeable x2apic id 06:48:29 DEBUG| [stdout] PASS: sane x2apic id 06:48:29 DEBUG| [stdout] PASS: x2apic id matches cpuid 06:48:29 DEBUG| [stdout] PASS: correct xapic id after reset 06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic disabled 06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:EDX.APIC[bit 9] is clear 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: ffffffff 06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: 0 06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: f 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: ffffffff 06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic enabled in xAPIC mode 06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:EDX.APIC[bit 9] is set 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: 50014 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: 0 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: f0 06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic enabled in x2APIC mode 06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:EDX.APIC[bit 9] is set 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: ffffffff 06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: 0 06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: f 06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: ffffffff 06:48:29 DEBUG| [stdout] x2apic enabled 06:48:29 DEBUG| [stdout] PASS: x2apic enabled to invalid state 06:48:29 DEBUG| [stdout] PASS: x2apic enabled to apic enabled 06:48:29 DEBUG| [stdout] PASS: x2apic enabled to disabled state 06:48:29 DEBUG| [stdout] PASS: disabled to invalid state 06:48:29 DEBUG| [stdout] PASS: disabled to x2apic enabled 06:48:29 DEBUG| [stdout] PASS: apic enabled to invalid state 06:48:29 DEBUG| [stdout] PASS: apicbase: relocate apic 06:48:29 DEBUG| [stdout] PASS: apicbase: reserved physaddr bits 06:48:29 DEBUG| [stdout] PASS: apicbase: reserved low bits 06:48:29 DEBUG| [stdout] PASS: self ipi 06:48:29 DEBUG| [stdout] starting broadcast (x2apic) 06:48:29 DEBUG| [stdout] PASS: APIC physical broadcast address 06:48:29 DEBUG| [stdout] PASS: APIC physical broadcast shorthand 06:48:29 DEBUG| [stdout] SKIP: PV IPIs testing: h-call not available 06:48:29 DEBUG| [stdout] PASS: nmi-after-sti 06:49:04 DEBUG| [stdout] PASS: multiple nmi 06:49:04 DEBUG| [stdout] PASS: APIC LVT timer one shot 06:49:04 DEBUG| [stdout] starting apic change mode 06:49:04 DEBUG| [stdout] PASS: TMICT value reset 06:49:04 DEBUG| [stdout] PASS: TMCCT should have a non-zero value 06:49:04 DEBUG| [stdout] PASS: TMCCT should have reached 0 06:49:04 DEBUG| [stdout] PASS: TMCCT should have a non-zero value 06:49:04 DEBUG| [stdout] PASS: TMCCT should not be reset to TMICT value 06:49:04 DEBUG| [stdout] PASS: TMCCT should be reset to the initial-count 06:49:04 DEBUG| [stdout] PASS: TMCCT should not be reset to init 06:49:04 DEBUG| [stdout] PASS: TMCCT should have reach zero 06:49:04 DEBUG| [stdout] PASS: TMCCT should stay at zero 06:49:04 DEBUG| [stdout] PASS: tsc deadline timer 06:49:04 DEBUG| [stdout] PASS: tsc deadline timer clearing 06:49:04 DEBUG| [stdout] SUMMARY: 51 tests, 1 skipped 06:49:04 DEBUG| [stdout] PASS apic (51 tests, 1 skipped) 06:49:06 INFO | GOOD ubuntu_kvm_unit_tests.apic ubuntu_kvm_unit_tests.apic timestamp=1602571746 localtime=Oct 13 06:49:06 completed successfully 06:49:06 INFO | END GOOD ubuntu_kvm_unit_tests.apic ubuntu_kvm_unit_tests.apic timestamp=1602571746 localtime=Oct 13 06:49:06
Looks like it will take about 40 seconds to run on Azure Standard-D48-v3, bump the timeout threshold to 60 or 90 should be able to solve this bug.
06:48:28 INFO | START ubuntu_ kvm_unit_ tests.apic ubuntu_ kvm_unit_ tests.apic timestamp= 1602571708 timeout=1800 localtime=Oct 13 06:48:28 _record_ indent now set to 2 unexpected_ reboot now set to ('ubuntu_ kvm_unit_ tests.apic' , 'ubuntu_ kvm_unit_ tests.apic' ) azure/autotest/ client/ tmp/ubuntu_ kvm_unit_ tests/src/ kvm-unit- tests/tests/ apic' qemu-system- x86_64 -nodefaults -device pc-testdev -device isa-debug- exit,iobase= 0xf4,iosize= 0x4 -vnc none -serial stdio -device pci-testdev -machine accel=kvm -kernel /tmp/tmp.kHcsacTcCc -smp 2 -cpu qemu64, +x2apic, +tsc-deadline # -initrd /tmp/tmp.Mc6ec4A6zG :ECX.svm [bit 2] :ECX.svm [bit 2] EDX.APIC[ bit 9] is clear EDX.APIC[ bit 9] is set EDX.APIC[ bit 9] is set kvm_unit_ tests.apic ubuntu_ kvm_unit_ tests.apic timestamp= 1602571746 localtime=Oct 13 06:49:06 completed successfully kvm_unit_ tests.apic ubuntu_ kvm_unit_ tests.apic timestamp= 1602571746 localtime=Oct 13 06:49:06
06:48:28 DEBUG| Persistent state client.
06:48:28 DEBUG| Persistent state client.
06:48:28 DEBUG| Waiting for pid 20233 for 1800 seconds
06:48:28 DEBUG| Running 'kvm-ok'
06:48:28 DEBUG| [stdout] INFO: /dev/kvm exists
06:48:28 DEBUG| [stdout] KVM acceleration can be used
06:48:28 DEBUG| Running '/home/
06:48:28 DEBUG| [stdout] BUILD_HEAD=4671e4ba
06:48:28 DEBUG| [stdout] timeout -k 1s --foreground 60 /usr/bin/
06:48:28 DEBUG| [stderr] qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H
06:48:28 DEBUG| [stderr] qemu-system-x86_64: warning: host doesn't support requested feature: CPUID.80000001H
06:48:29 DEBUG| [stdout] enabling apic
06:48:29 DEBUG| [stdout] enabling apic
06:48:29 DEBUG| [stdout] paging enabled
06:48:29 DEBUG| [stdout] cr0 = 80010011
06:48:29 DEBUG| [stdout] cr3 = 45a000
06:48:29 DEBUG| [stdout] cr4 = 20
06:48:29 DEBUG| [stdout] apic version: 50014
06:48:29 DEBUG| [stdout] PASS: apic existence
06:48:29 DEBUG| [stdout] PASS: xapic id matches cpuid
06:48:29 DEBUG| [stdout] PASS: writeable xapic id
06:48:29 DEBUG| [stdout] PASS: non-writeable x2apic id
06:48:29 DEBUG| [stdout] PASS: sane x2apic id
06:48:29 DEBUG| [stdout] PASS: x2apic id matches cpuid
06:48:29 DEBUG| [stdout] PASS: correct xapic id after reset
06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic disabled
06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: ffffffff
06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: 0
06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: f
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: ffffffff
06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic enabled in xAPIC mode
06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: 50014
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: 0
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: f0
06:48:29 DEBUG| [stdout] PASS: apic_disable: Local apic enabled in x2APIC mode
06:48:29 DEBUG| [stdout] PASS: apic_disable: CPUID.1H:
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00030: ffffffff
06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: 0
06:48:29 DEBUG| [stdout] PASS: apic_disable: CR8: f
06:48:29 DEBUG| [stdout] PASS: apic_disable: *0xfee00080: ffffffff
06:48:29 DEBUG| [stdout] x2apic enabled
06:48:29 DEBUG| [stdout] PASS: x2apic enabled to invalid state
06:48:29 DEBUG| [stdout] PASS: x2apic enabled to apic enabled
06:48:29 DEBUG| [stdout] PASS: x2apic enabled to disabled state
06:48:29 DEBUG| [stdout] PASS: disabled to invalid state
06:48:29 DEBUG| [stdout] PASS: disabled to x2apic enabled
06:48:29 DEBUG| [stdout] PASS: apic enabled to invalid state
06:48:29 DEBUG| [stdout] PASS: apicbase: relocate apic
06:48:29 DEBUG| [stdout] PASS: apicbase: reserved physaddr bits
06:48:29 DEBUG| [stdout] PASS: apicbase: reserved low bits
06:48:29 DEBUG| [stdout] PASS: self ipi
06:48:29 DEBUG| [stdout] starting broadcast (x2apic)
06:48:29 DEBUG| [stdout] PASS: APIC physical broadcast address
06:48:29 DEBUG| [stdout] PASS: APIC physical broadcast shorthand
06:48:29 DEBUG| [stdout] SKIP: PV IPIs testing: h-call not available
06:48:29 DEBUG| [stdout] PASS: nmi-after-sti
06:49:04 DEBUG| [stdout] PASS: multiple nmi
06:49:04 DEBUG| [stdout] PASS: APIC LVT timer one shot
06:49:04 DEBUG| [stdout] starting apic change mode
06:49:04 DEBUG| [stdout] PASS: TMICT value reset
06:49:04 DEBUG| [stdout] PASS: TMCCT should have a non-zero value
06:49:04 DEBUG| [stdout] PASS: TMCCT should have reached 0
06:49:04 DEBUG| [stdout] PASS: TMCCT should have a non-zero value
06:49:04 DEBUG| [stdout] PASS: TMCCT should not be reset to TMICT value
06:49:04 DEBUG| [stdout] PASS: TMCCT should be reset to the initial-count
06:49:04 DEBUG| [stdout] PASS: TMCCT should not be reset to init
06:49:04 DEBUG| [stdout] PASS: TMCCT should have reach zero
06:49:04 DEBUG| [stdout] PASS: TMCCT should stay at zero
06:49:04 DEBUG| [stdout] PASS: tsc deadline timer
06:49:04 DEBUG| [stdout] PASS: tsc deadline timer clearing
06:49:04 DEBUG| [stdout] SUMMARY: 51 tests, 1 skipped
06:49:04 DEBUG| [stdout] PASS apic (51 tests, 1 skipped)
06:49:06 INFO | GOOD ubuntu_
06:49:06 INFO | END GOOD ubuntu_