Mark Kettenis wrote:
>Maybe you can add some printf's to figure out why the timeout is
>happening? Is it actually doing a delay? Is the delay too long? Or
>too short?
Yes, the delay is okay. The problem is that when "cold" is 1, the vblank
counter never changes during a call to drm_wait_one_vblank, so the
condition inside __wait_event_intr_timeout is never true and there is a
timeout. In fact the vblank counter does not even change across multiple
calls to drm_wait_one_vblank, unless there is in-between a call to
vblank_disable_and_save followed by a call to drm_vblank_enable.
It looks like this when timing every iteration of the delay(tick) loop in
the patch you proposed (delays are computed using nanouptime(9); see the
dmesg at the end of this email for the complete output):
XXX [drm_vblank_on]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10004065, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
(repeat the same thing three more times)
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 9999874, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
etc.
So the vblank counter only changes (from 1 to 46 in the example above) in
the interval of time between a call to vblank_disable_and_save and a call
to drm_vblank_enable. In the interval of time between a call to
drm_vblank_enable and a call to vblank_disable_and_save (which is the
interval of time when drm_wait_one_vblank is called), the vblank counter is
constant, so __wait_event_intr_timeout times out and drm_wait_one_vblank
then gives a time out warning message.
It's also a little bit strange that the vblank counter is always 1 the
first time drm_wait_one_vblank is called. I would have expected a much
higher value...
Anyway, I tried to see whether I could call vblank_disable_and_save right
before the start of the delay(tick) loop inside __wait_event_intr_timeout,
and call drm_vblank_enable right after the end of the loop, so that the
vblank counter would be able to change inside the loop to make the
condition true. But vblank_disable_and_save and drm_vblank_enable require
"dev" and "pipe" arguments that are not always available when
__wait_event_intr_timeout is called, so that doesn't work (or at least it
doesn't work without more surgery to some files beyond drm_linux.{h,c}).
So, unless you know what's going on with the vblank counter, how about
using this patch:
########
Index: dev/pci/drm/drm_irq.c
===================================================================
RCS file: /cvs/src/sys/dev/pci/drm/drm_irq.c,v
retrieving revision 1.70
diff -u -p -u -p -r1.70 drm_irq.c
--- dev/pci/drm/drm_irq.c 28 Mar 2018 05:27:28 -0000 1.70
+++ dev/pci/drm/drm_irq.c 17 Aug 2018 14:11:31 -0000
@@ -1317,7 +1317,7 @@ void drm_wait_one_vblank(struct drm_devi
int ret;
u32 last;
- if (WARN_ON(pipe >= dev->num_crtcs) || cold)
+ if (WARN_ON(pipe >= dev->num_crtcs))
return;
ret = drm_vblank_get(dev, pipe);
Index: dev/pci/drm/drm_linux.h
===================================================================
RCS file: /cvs/src/sys/dev/pci/drm/drm_linux.h,v
retrieving revision 1.90
diff -u -p -u -p -r1.90 drm_linux.h
--- dev/pci/drm/drm_linux.h 27 Jul 2018 21:11:31 -0000 1.90
+++ dev/pci/drm/drm_linux.h 17 Aug 2018 14:11:41 -0000
@@ -604,10 +604,12 @@ __remove_wait_queue(wait_queue_head_t *h
#define __wait_event_intr_timeout(wq, condition, timo, prio) \
({ \
long ret = timo; \
- do { \
+ if (cold) { \
+ /* Simulate one vblank interval using one tick. */ \
+ delay(tick); \
+ ret = 1; \
+ } else do { \
int deadline, __error; \
- \
- KASSERT(!cold); \
\
mtx_enter(&sch_mtx); \
atomic_inc_int(&(wq).count); \
########
It's ad-hoc but it's simple. My DAC timing problem that makes the console
resolution wrong is fixed with about a 2 microseconds delay, so one tick of
delay is plenty enough time to make the thing work.
Philippe
########
OpenBSD 6.4-beta (GENERIC) #19: Fri Aug 17 23:54:46 CST 2018
[email protected]:/usr/src/sys/arch/i386/compile/GENERIC
real mem = 1063600128 (1014MB)
avail mem = 1028182016 (980MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
XXX initclocks start
XXX initclocks end
XXX cpu_configure start
mainbus0 at root
bios0 at mainbus0: date 05/29/07, BIOS32 rev. 0 @ 0xfd740, SMBIOS rev. 2.33 @
0xe0010 (64 entries)
bios0: vendor IBM version "70ET69WW (1.29 )" date 05/29/2007
bios0: IBM 1875E5U
acpi0 at bios0: rev 2
acpi0: sleep states S0 S3 S4 S5
acpi0: tables DSDT FACP SSDT ECDT TCPA APIC MCFG BOOT
acpi0: wakeup devices LID_(S3) SLPB(S3) UART(S3) EXP0(S4) EXP1(S4) EXP2(S4)
EXP3(S4) PCI1(S4) USB0(S3) USB1(S3) USB3(S3) USB7(S3) AC9M(S4)
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpiec0 at acpi0
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Pentium(R) M processor 1.60GHz ("GenuineIntel" 686-class) 1.60
GHz
cpu0:
FPU,V86,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,TM,PBE,NXE,EST,TM2,PERF,MELTDOWN
mtrr: Pentium Pro MTRR support, 8 var ranges, 88 fixed ranges
cpu0: apic clock running at 133MHz
ioapic0 at mainbus0: apid 1 pa 0xfec00000, version 20, 24 pins
, remapped to apid 1
acpimcfg0 at acpi0 addr 0xe0000000, bus 0-255
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus 2 (EXP0)
acpiprt2 at acpi0: bus -1 (EXP1)
acpiprt3 at acpi0: bus 3 (EXP2)
acpiprt4 at acpi0: bus -1 (EXP3)
acpiprt5 at acpi0: bus 4 (PCI1)
acpicpu0 at acpi0: C1 (unknown FFH class 0): !C3(250@85 io@0x1015), !C2(500@1
io@0x1014), C1(@1 halt!), PSS
acpipwrres0 at acpi0: PUBS, resource for USB0, USB1, USB7
acpitz0 at acpi0: critical temperature is 99 degC
acpibtn0 at acpi0: LID_
acpibtn1 at acpi0: SLPB
acpicmos0 at acpi0
"IBM0057" at acpi0 not configured
"NSC1100" at acpi0 not configured
acpibat0 at acpi0: BAT0 model "IBM-92P1091" serial 1313 type LION oem
"Panasonic"
acpiac0 at acpi0: AC unit online
acpithinkpad0 at acpi0
acpidock0 at acpi0: DOCK not docked (0)
acpivideo0 at acpi0: VID_
bios0: ROM list: 0xc0000/0xf600! 0xcf800/0x1600 0xd1000/0x1000 0xdc000/0x4000!
0xe0000/0x10000
cpu0: Enhanced SpeedStep 1597 MHz: speeds: 1600, 1333, 1066, 800 MHz
pci0 at mainbus0 bus 0: configuration mode 1 (bios)
pchb0 at pci0 dev 0 function 0 "Intel 82915GM Host" rev 0x03
inteldrm0 at pci0 dev 2 function 0 "Intel 82915GM Video" rev 0x03
drm0 at inteldrm0
intagp0 at inteldrm0
agp0 at intagp0: aperture at 0xb0000000, size 0x10000000
inteldrm0: apic 1 int 16
XXX [drm_vblank_on]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10004065, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999874, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 1, cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 1
vblank wait timed out on crtc 0
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999875, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 9999874, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999873, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 9999874, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 46, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000155, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 46
vblank wait timed out on crtc 0
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
inteldrm0: 1024x768, 32bpp
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 94, cold:
1
XXX [__wait_event_intr_timeout] delay(tick): 0 9999874, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 94
vblank wait timed out on crtc 1
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 128,
cold: 1
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 10, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 9, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 8, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 7, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 6, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 5, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 4, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 3, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000154, ret: 2, (condition): 0
XXX [__wait_event_intr_timeout] delay(tick): 0 10000153, ret: 1, (condition): 0
XXX [__wait_event_intr_timeout] (condition) after loop: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 128
vblank wait timed out on crtc 1
wsdisplay0 at inteldrm0 mux 1: console (std, vt100 emulation)
wsdisplay0: screen 1-5 added (std, vt100 emulation)
"Intel 82915GM Video" rev 0x03 at pci0 dev 2 function 1 not configured
ppb0 at pci0 dev 28 function 0 "Intel 82801FB PCIE" rev 0x03: apic 1 int 20
pci1 at ppb0 bus 2
bge0 at pci1 dev 0 function 0 "Broadcom BCM5751M" rev 0x11, BCM5750 B1
(0x4101): apic 1 int 16, address 00:10:c6:e1:f8:03
brgphy0 at bge0 phy 1: BCM5750 10/100/1000baseT PHY, rev. 0
ppb1 at pci0 dev 28 function 2 "Intel 82801FB PCIE" rev 0x03: apic 1 int 22
pci2 at ppb1 bus 3
uhci0 at pci0 dev 29 function 0 "Intel 82801FB USB" rev 0x03: apic 1 int 16
uhci1 at pci0 dev 29 function 1 "Intel 82801FB USB" rev 0x03: apic 1 int 17
uhci2 at pci0 dev 29 function 2 "Intel 82801FB USB" rev 0x03: apic 1 int 18
uhci3 at pci0 dev 29 function 3 "Intel 82801FB USB" rev 0x03: apic 1 int 19
ehci0 at pci0 dev 29 function 7 "Intel 82801FB USB" rev 0x03: apic 1 int 19
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 configuration 1 interface 0 "Intel EHCI root hub" rev 2.00/1.00
addr 1
ppb2 at pci0 dev 30 function 0 "Intel 82801BAM Hub-to-PCI" rev 0xd3
pci3 at ppb2 bus 4
cbb0 at pci3 dev 0 function 0 "TI PCI1510 CardBus" rev 0x00: apic 1 int 16
ath0 at pci3 dev 2 function 0 "Atheros AR5212" rev 0x01: apic 1 int 21
ath0: AR5213A 5.9 phy 4.3 rf5112a 3.6 eeprom 4.8, WOR01W, address
00:14:a4:72:72:c6
cardslot0 at cbb0 slot 0 flags 0
cardbus0 at cardslot0: bus 5 device 0 cacheline 0x8, lattimer 0xb0
pcmcia0 at cardslot0
auich0 at pci0 dev 30 function 2 "Intel 82801FB AC97" rev 0x03: apic 1 int 22,
ICH6
ac97: codec id 0x41445374 (Analog Devices AD1981B)
ac97: codec features headphone, 20 bit DAC, No 3D Stereo
audio0 at auich0
ichpcib0 at pci0 dev 31 function 0 "Intel 82801FBM LPC" rev 0x03: PM disabled
pciide0 at pci0 dev 31 function 2 "Intel 82801FBM SATA" rev 0x03: DMA, channel
0 wired to compatibility, channel 1 wired to compatibility
wd0 at pciide0 channel 0 drive 0: <HTS541060G9AT00>
wd0: 16-sector PIO, LBA, 57231MB, 117210240 sectors
wd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 5
atapiscsi0 at pciide0 channel 1 drive 0
scsibus1 at atapiscsi0: 2 targets
cd0 at scsibus1 targ 0 lun 0: <MIT[HITI, UJDI7>5(D^D/CLR_, 1.0:> ATAPI 5/cdrom
removable
cd0(pciide0:1:0): using PIO mode 4, Ultra-DMA mode 2
ichiic0 at pci0 dev 31 function 3 "Intel 82801FB SMBus" rev 0x03: apic 1 int 23
iic0 at ichiic0
usb1 at uhci0: USB revision 1.0
uhub1 at usb1 configuration 1 interface 0 "Intel UHCI root hub" rev 1.00/1.00
addr 1
usb2 at uhci1: USB revision 1.0
uhub2 at usb2 configuration 1 interface 0 "Intel UHCI root hub" rev 1.00/1.00
addr 1
usb3 at uhci2: USB revision 1.0
uhub3 at usb3 configuration 1 interface 0 "Intel UHCI root hub" rev 1.00/1.00
addr 1
usb4 at uhci3: USB revision 1.0
uhub4 at usb4 configuration 1 interface 0 "Intel UHCI root hub" rev 1.00/1.00
addr 1
isa0 at ichpcib0
isadma0 at isa0
pckbc0 at isa0 port 0x60/5 irq 1 irq 12
pckbd0 at pckbc0 (kbd slot)
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pms0 at pckbc0 (aux slot)
wsmouse0 at pms0 mux 0
wsmouse1 at pms0 mux 0
pms0: Synaptics touchpad, firmware 5.9, 0x2c6ab1 0x0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
lpt2 at isa0 port 0x3bc/4: polled
aps0 at isa0 port 0x1600/31
npx0 at isa0 port 0xf0/16: reported by CPUID; using exception 16
XXX cpu_configure end
XXX config_pending start
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 49, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 50
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 52, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 53
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 55, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 56
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 57, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 58
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 59, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 60
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
XXX [drm_vblank_on]
XXX [drm_vblank_enable]
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 65, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 66
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 66, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 67
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 68, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 69
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 69, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 70
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 70, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 71
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 71, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 72
XXX [drm_wait_one_vblank] drm_vblank_count before wait_event_timeout: 73, cold: 0
XXX [drm_wait_one_vblank] drm_vblank_count after wait_event_timeout: 74
XXX [drm_vblank_off]
XXX [vblank_disable_and_save]
umass0 at uhub0 port 3 configuration 1 interface 0 "SanDisk Cruzer Blade" rev
2.00/1.26 addr 2
umass0: using SCSI over Bulk-Only
scsibus2 at umass0: 2 targets, initiator 0
sd0 at scsibus2 targ 1 lun 0: <SanDisk, Cruzer Blade, 1.26> SCSI3 0/direct
removable serial.07815567070513118275
sd0: 3819MB, 512 bytes/sector, 7821312 sectors
ugen0 at uhub3 port 2 "STMicroelectronics Biometric Coprocessor" rev 1.00/0.01
addr 2
XXX config_pending end
vscsi0 at root
scsibus3 at vscsi0: 256 targets
softraid0 at root
scsibus4 at softraid0: 256 targets
root on sd0a (6e51a9b277043634.a) swap on sd0b dump on sd0b
