Bug 36052

Summary:	System hang during X startup (non-kms, regression, bisected)
Product:	Drivers	Reporter:	Kirill Smelkov (kirr)
Component:	Video(DRI - Intel)	Assignee:	drivers_video-dri-intel (drivers_video-dri-intel)
Status:	CLOSED CODE_FIX
Severity:	blocking	CC:	florian
Priority:	P1
Hardware:	All
OS:	Linux
Kernel Version:	2.6.38	Subsystem:
Regression:	Yes	Bisected commit-id:
Bug Depends on:
Bug Blocks:	27352
Attachments:	0001-Revert-drm-i915-Initialise-ring-vfuncs-for-old-DRI-p.patch

Description Kirill Smelkov 2011-05-28 10:29:55 UTC

[ In follow-up to closed as unreproducible bug33662 ]

I'm observing X freeze on startup on Debian Lenny based system after 2.6.37.* -> 2.6.38.* kernel upgrade: X
tries to start, monitor backlight blinks one time, and then whole system freeze
- display is black, no reaction to keyboard (sysrq not working) and no network
(i.e. ping from other host is not working).

It used to work with 2.6.37.6 and earlier kernels without problem.

CPU: Intel Atom N270
GPU: Intel Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics
Controller
MB:  iEi PCISA-945GSE

libdrm2                     2.3.1-2
mesa                        7.0.3-7
xserver-xorg-core           1.4.2-10
xserver-xorg-video-intel    2.3.2-2+lenny8

Kernel built with:

    gcc (Debian 4.3.2-1.1) 4.3.2



After turning on netconsole, I see it BUGS at NULL pointer dereference in i915_driver_irq_handler:

# normal startup here, then X is started and oops:
[  213.443542] BUG: unable to handle kernel  NULL pointer dereference  at 00000084
[  213.443566] IP:  [<c11f7e0f>] i915_driver_irq_handler+0x11f/0xa90
[  213.443587] *pde = 00000000  
[  213.443597] Oops: 0000 [#1]  PREEMPT  
[  213.443608] last sysfs file: /sys/devices/virtual/dmi/id/board_asset_tag
[  213.443617] Modules linked in: 
[  213.443626] 
[  213.443634] Pid: 2511, comm: ecu-ns-proxy Not tainted 2.6.38.7--NAVY #25   ICP / iEi PCISA-945GSE / PCISA-945GSE(B125) 
[  213.443657] EIP: 0060:[<c11f7e0f>] EFLAGS: 00210082 CPU: 0
[  213.443666] EIP is at i915_driver_irq_handler+0x11f/0xa90
[  213.443674] EAX: 00000000 EBX: 00000000 ECX: 00000000 EDX: dffee898
[  213.443682] ESI: 00000000 EDI: deffc000 EBP: dec09fc0 ESP: dec09f3c
[  213.443690]  DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 0068
[  213.443698] Process ecu-ns-proxy (pid: 2511, ti=dec08000 task=dec894e0 task.ti=dc592000)
[  213.443705] Stack:
[  213.443711]  00000002  c140f68a  c1398264  c1410474  de458400  dec09f64  c12348af  00011000 
[  213.443750]  de45c400  de458400  dec09f90  c123dc18  dec09f84  de4585bc  deffc2b8  deffc01c 



And so then I've bisected this to

commit e8616b6ced6137085e6657cc63bc2fe3900b8616
Author: Chris Wilson <chris@chris-wilson.co.uk>
Date:   Thu Jan 20 09:57:11 2011 +0000

    drm/i915: Initialise ring vfuncs for old DRI paths

    We weren't setting up the vfunc table when initialising the old DRI
    ringbuffer, leading to such OOPSes as:

    BUG: unable to handle kernel NULL pointer dereference at (null)
    IP: [<(null)>] (null)
    PGD 10c441067 PUD 1185e5067 PMD 0
    Oops: 0010 [#1] PREEMPT SMP
    last sysfs file: /sys/class/dmi/id/chassis_asset_tag
    CPU 3
    Modules linked in: i915 drm_kms_helper drm fb fbdev i2c_algo_bit
    cfbcopyarea video backlight output cfbimgblt cfbfillrect autofs4 ipv6
    nfs lockd fscache nfs_acl auth_rpcgss sunrpc coretemp hwmon_vid mousedev
    usbhid hid option usb_wwan snd_hda_codec_via asus_atk0110 atl1e
    usbserial snd_hda_intel snd_hda_codec firmware_class snd_hwdep snd_pcm
    snd_seq snd_timer snd_seq_device processor parport_pc thermal snd
    thermal_sys parport 8250_pnp button rng_core rtc_cmos shpchp hwmon
    rtc_core ehci_hcd pci_hotplug uhci_hcd soundcore tpm_tis i2c_i801
    rtc_lib tpm serio_raw snd_page_alloc tpm_bios i2c_core usbcore psmouse
    intel_agp sg pcspkr sr_mod evdev cdrom ext3 jbd mbcache dm_mod sd_mod
    ata_piix libata scsi_mod unix
    Jan 18 15:49:29 lithui kernel:
    Pid: 3605, comm: Xorg Not tainted 2.6.36.2 #5 P5KPL-CM/System Product
    Name
    RIP: 0010:[<0000000000000000>]  [<(null)>] (null)
    RSP: 0018:ffff8801150d1d40  EFLAGS: 00010202
    RAX: 000000000001ffff RBX: ffff88011a011b00 RCX: 000000000001a704
    RDX: ffff880118566028 RSI: ffff880118566028 RDI: ffff880117876800
    RBP: ffff8801150d1d48 R08: ffff8801195fe300 R09: 00000000c0086444
    R10: 0000000000000001 R11: 0000000000003206 R12: ffff880117876800
    R13: ffff880118566000 R14: ffff880117876820 R15: ffff8801150d1df8
    FS:  00007f1038d456e0(0000) GS:ffff880001780000(0000)
    knlGS:0000000000000000
    CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
    CR2: 0000000000000000 CR3: 00000001187e7000 CR4: 00000000000006e0
    DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
    DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
    Process Xorg (pid: 3605, threadinfo ffff8801150d0000, task
    ffff88011b016e40)
    Stack:
    ffffffffa043b8e6 ffff8801150d1d98 ffffffffa041768b dead000000000000
    <0> 0000000000000048 00007f1023f2a000 0000000000000044 0000000000000008
    <0> ffff88010d26bd80 ffff880117876800 ffff8801150d1df8 ffff8801150d1ea8
    Call Trace:
    [<ffffffffa043b8e6>] ? intel_ring_advance+0x16/0x20 [i915]
    [<ffffffffa041768b>] i915_irq_emit+0x15b/0x240 [i915]
    [<ffffffffa03ea7b1>] drm_ioctl+0x1f1/0x460 [drm]
    [<ffffffffa0417530>] ? i915_irq_emit+0x0/0x240 [i915]
    [<ffffffff810dd8f1>] ? do_sync_read+0xd1/0x120
    [<ffffffff81025b1f>] ? do_page_fault+0x1df/0x3d0
    [<ffffffff810ed5c7>] do_vfs_ioctl+0x97/0x550
    [<ffffffff8115c2ea>] ? security_file_permission+0x7a/0x90
    [<ffffffff810edb19>] sys_ioctl+0x99/0xa0
    [<ffffffff810024ab>] system_call_fastpath+0x16/0x1b
    Code:  Bad RIP value.
    RIP  [<(null)>] (null)
    RSP <ffff8801150d1d40>
    CR2: 0000000000000000

    Reported-by: Herbert Xu <herbert@gondor.apana.org.au>
    Tested-by: Herbert Xu <herbert@gondor.apana.org.au>
    Bugzilla: https://bugs.freedesktop.org/show_bug.cgi?id=29153
    Bugzilla: https://bugzilla.kernel.org/show_bug.cgi?id=23172
    Signed-off-by: Chris Wilson <chris@chris-wilson.co.uk>
    Cc: stable@kernel.org


This is 100% reproducible on several iEi PCISA-945GSE motherboards.


Attaching full dmesg and `X -verbose` output for the reference.

Thanks beforehand,
Kirill



---- 8< ---- (full dmesg)
navy3:~# dmesg 
[    0.000000] Linux version 2.6.38.7--NAVY (kirr@tugrik) (gcc version 4.3.2 (Debian 4.3.2-1.1) ) #25 PREEMPT Sat May 28 13:39:47 MSD 2011
[    0.000000] Atom PSE erratum detected, BIOS microcode update recommended
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 0000000000097000 (usable)
[    0.000000]  BIOS-e820: 0000000000097000 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000001f7b0000 (usable)
[    0.000000]  BIOS-e820: 000000001f7b0000 - 000000001f7be000 (ACPI data)
[    0.000000]  BIOS-e820: 000000001f7be000 - 000000001f7f0000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000001f7f0000 - 000000001f800000 (reserved)
[    0.000000]  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  BIOS-e820: 00000000fff00000 - 0000000100000000 (reserved)
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] DMI present.
[    0.000000] DMI: ICP / iEi PCISA-945GSE/PCISA-945GSE(B125), BIOS 080015  03/13/2009
[    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[    0.000000] last_pfn = 0x1f7b0 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-DFFFF uncachable
[    0.000000]   E0000-E3FFF write-protect
[    0.000000]   E4000-E7FFF write-through
[    0.000000]   E8000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask 0E0000000 write-back
[    0.000000]   1 base 01F800000 mask 0FF800000 uncachable
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] found SMP MP-table at [c00ff780] ff780
[    0.000000] initial memory mapped : 0 - 01c00000
[    0.000000] init_memory_mapping: 0000000000000000-000000001f7b0000
[    0.000000]  0000000000 - 001f7b0000 page 4k
[    0.000000] kernel direct mapping tables up to 1f7b0000 @ 1b7f000-1c00000
[    0.000000] 503MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 1f7b0000
[    0.000000]   low ram: 0 - 1f7b0000
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x0001f7b0
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000010 -> 0x00000097
[    0.000000]     0: 0x00000100 -> 0x0001f7b0
[    0.000000] On node 0 totalpages: 128823
[    0.000000] free_area_init_node: node 0, pgdat c149ed60, node_mem_map df3c0200
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3943 pages, LIFO batch:0
[    0.000000]   Normal zone: 976 pages used for memmap
[    0.000000]   Normal zone: 123872 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] Intel MultiProcessor Specification v1.4
[    0.000000]     Virtual Wire compatibility mode.
[    0.000000] MPTABLE: OEM ID: Intel   
[    0.000000] MPTABLE: Product ID: Calistoga   
[    0.000000] MPTABLE: APIC at: 0xFEE00000
[    0.000000] Processor #0 (Bootup-CPU)
[    0.000000] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23
[    0.000000] Processors: 1
[    0.000000] nr_irqs_gsi: 40
[    0.000000] Allocating PCI resources starting at 1f800000 (gap: 1f800000:df600000)
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 127815
[    0.000000] Kernel command line: root=/dev/hdb1 netconsole=5555@192.168.72.123/eth0,5556@192.168.72.255/ vga=788 nosplash panic=60 ro 5
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 504088k/515776k available (3522k kernel code, 11204k reserved, 1224k data, 324k init, 0k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfffa4000 - 0xfffff000   ( 364 kB)
[    0.000000]     vmalloc : 0xdffb0000 - 0xfffa2000   ( 511 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xdf7b0000   ( 503 MB)
[    0.000000]       .init : 0xc14a3000 - 0xc14f4000   ( 324 kB)
[    0.000000]       .data : 0xc13709d6 - 0xc14a2cc0   (1224 kB)
[    0.000000]       .text : 0xc1000000 - 0xc13709d6   (3522 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] Preemptable hierarchical RCU implementation.
[    0.000000]  RCU-based detection of stalled CPUs is disabled.
[    0.000000]  Verbose stalled-CPUs detection is disabled.
[    0.000000] NR_IRQS:288
[    0.000000] CPU 0 irqstacks, hard=dec08000 soft=dec0a000
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Fast TSC calibration using PIT
[    0.000000] Detected 1595.858 MHz processor.
[    0.004004] Calibrating delay loop (skipped), value calculated using timer frequency.. 3191.71 BogoMIPS (lpj=6383432)
[    0.004029] pid_max: default: 32768 minimum: 301
[    0.004156] Mount-cache hash table entries: 512
[    0.004345] Atom PSE erratum detected, BIOS microcode update recommended
[    0.004365] mce: CPU supports 5 MCE banks
[    0.004381] using mwait in idle threads.
[    0.004397] CPU: Intel(R) Atom(TM) CPU N270   @ 1.60GHz stepping 02
[    0.005080] Performance Events: PEBS fmt0+, Atom events, Intel PMU driver.
[    0.005114] ... version:                3
[    0.005124] ... bit width:              40
[    0.005134] ... generic registers:      2
[    0.005144] ... value mask:             000000ffffffffff
[    0.005156] ... max period:             000000007fffffff
[    0.005167] ... fixed-purpose events:   3
[    0.005177] ... event mask:             0000000700000003
[    0.005314] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.005406] ExtINT not setup in hardware but reported by MP table
[    0.005716] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[    0.152643] NET: Registered protocol family 16
[    0.153519] PCI: Using configuration type 1 for base access
[    0.162928] bio: create slab <bio-0> at 0
[    0.163299] vgaarb: loaded
[    0.163692] SCSI subsystem initialized
[    0.164133] usbcore: registered new interface driver usbfs
[    0.164235] usbcore: registered new interface driver hub
[    0.164353] usbcore: registered new device driver usb
[    0.164604] PCI: Probing PCI hardware
[    0.164619] PCI: Probing PCI hardware (bus 00)
[    0.164741] pci 0000:00:00.0: [8086:27ac] type 0 class 0x000600
[    0.164802] pci 0000:00:02.0: [8086:27ae] type 0 class 0x000300
[    0.164823] pci 0000:00:02.0: reg 10: [mem 0xfe980000-0xfe9fffff]
[    0.164835] pci 0000:00:02.0: reg 14: [io  0xcc80-0xcc87]
[    0.164847] pci 0000:00:02.0: reg 18: [mem 0xd0000000-0xdfffffff pref]
[    0.164859] pci 0000:00:02.0: reg 1c: [mem 0xfe940000-0xfe97ffff]
[    0.164913] pci 0000:00:02.1: [8086:27a6] type 0 class 0x000380
[    0.164929] pci 0000:00:02.1: reg 10: [mem 0xfe880000-0xfe8fffff]
[    0.165038] pci 0000:00:1c.0: [8086:27d0] type 1 class 0x000604
[    0.165125] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.165134] pci 0000:00:1c.0: PME# disabled
[    0.165168] pci 0000:00:1c.1: [8086:27d2] type 1 class 0x000604
[    0.165254] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.165263] pci 0000:00:1c.1: PME# disabled
[    0.165302] pci 0000:00:1d.0: [8086:27c8] type 0 class 0x000c03
[    0.165363] pci 0000:00:1d.0: reg 20: [io  0xcc00-0xcc1f]
[    0.165414] pci 0000:00:1d.1: [8086:27c9] type 0 class 0x000c03
[    0.165475] pci 0000:00:1d.1: reg 20: [io  0xc880-0xc89f]
[    0.165524] pci 0000:00:1d.2: [8086:27ca] type 0 class 0x000c03
[    0.165586] pci 0000:00:1d.2: reg 20: [io  0xc800-0xc81f]
[    0.165634] pci 0000:00:1d.3: [8086:27cb] type 0 class 0x000c03
[    0.165695] pci 0000:00:1d.3: reg 20: [io  0xc480-0xc49f]
[    0.165756] pci 0000:00:1d.7: [8086:27cc] type 0 class 0x000c03
[    0.165787] pci 0000:00:1d.7: reg 10: [mem 0xfe93bc00-0xfe93bfff]
[    0.165887] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[    0.165896] pci 0000:00:1d.7: PME# disabled
[    0.165928] pci 0000:00:1e.0: [8086:2448] type 1 class 0x000604
[    0.166018] pci 0000:00:1f.0: [8086:27b9] type 0 class 0x000601
[    0.166144] pci 0000:00:1f.0: Force enabled HPET at 0xfed00000
[    0.166162] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 1 PIO at 0a00 (mask 003f)
[    0.166183] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 2 PIO at 02e0 (mask 003f)
[    0.166247] pci 0000:00:1f.1: [8086:27df] type 0 class 0x000101
[    0.166269] pci 0000:00:1f.1: reg 10: [io  0x0000-0x0007]
[    0.166286] pci 0000:00:1f.1: reg 14: [io  0x0000-0x0003]
[    0.166302] pci 0000:00:1f.1: reg 18: [io  0x08f0-0x08f7]
[    0.166319] pci 0000:00:1f.1: reg 1c: [io  0x08f8-0x08fb]
[    0.166336] pci 0000:00:1f.1: reg 20: [io  0xffa0-0xffaf]
[    0.166390] pci 0000:00:1f.2: [8086:27c4] type 0 class 0x000101
[    0.166415] pci 0000:00:1f.2: reg 10: [io  0xc400-0xc407]
[    0.166431] pci 0000:00:1f.2: reg 14: [io  0xc080-0xc083]
[    0.166446] pci 0000:00:1f.2: reg 18: [io  0xc000-0xc007]
[    0.166462] pci 0000:00:1f.2: reg 1c: [io  0xbc80-0xbc83]
[    0.166477] pci 0000:00:1f.2: reg 20: [io  0xbc00-0xbc0f]
[    0.166493] pci 0000:00:1f.2: reg 24: [mem 0xfe93b800-0xfe93bbff]
[    0.166531] pci 0000:00:1f.2: PME# supported from D3hot
[    0.166539] pci 0000:00:1f.2: PME# disabled
[    0.166565] pci 0000:00:1f.3: [8086:27da] type 0 class 0x000c05
[    0.166638] pci 0000:00:1f.3: reg 20: [io  0x0400-0x041f]
[    0.166771] pci 0000:01:00.0: [10ec:8168] type 0 class 0x000200
[    0.166796] pci 0000:01:00.0: reg 10: [io  0xdc00-0xdcff]
[    0.166837] pci 0000:01:00.0: reg 18: [mem 0xfeaff000-0xfeafffff 64bit]
[    0.166865] pci 0000:01:00.0: reg 20: [mem 0xfdef0000-0xfdefffff 64bit pref]
[    0.166886] pci 0000:01:00.0: reg 30: [mem 0xfeac0000-0xfeadffff pref]
[    0.166942] pci 0000:01:00.0: supports D1 D2
[    0.166948] pci 0000:01:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.166957] pci 0000:01:00.0: PME# disabled
[    0.166986] pci 0000:00:1c.0: PCI bridge to [bus 01-01]
[    0.167004] pci 0000:00:1c.0:   bridge window [io  0xd000-0xdfff]
[    0.167014] pci 0000:00:1c.0:   bridge window [mem 0xfea00000-0xfeafffff]
[    0.167027] pci 0000:00:1c.0:   bridge window [mem 0xfde00000-0xfdefffff 64bit pref]
[    0.167118] pci 0000:02:00.0: [10ec:8168] type 0 class 0x000200
[    0.167143] pci 0000:02:00.0: reg 10: [io  0xec00-0xecff]
[    0.167184] pci 0000:02:00.0: reg 18: [mem 0xfebff000-0xfebfffff 64bit]
[    0.167212] pci 0000:02:00.0: reg 20: [mem 0xfdff0000-0xfdffffff 64bit pref]
[    0.167233] pci 0000:02:00.0: reg 30: [mem 0xfebc0000-0xfebdffff pref]
[    0.167289] pci 0000:02:00.0: supports D1 D2
[    0.167295] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.167304] pci 0000:02:00.0: PME# disabled
[    0.167333] pci 0000:00:1c.1: PCI bridge to [bus 02-02]
[    0.167350] pci 0000:00:1c.1:   bridge window [io  0xe000-0xefff]
[    0.167359] pci 0000:00:1c.1:   bridge window [mem 0xfeb00000-0xfebfffff]
[    0.167372] pci 0000:00:1c.1:   bridge window [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.167442] pci 0000:03:05.0: [3388:0022] type 1 class 0x000604
[    0.167544] pci 0000:03:05.0: supports D1 D2
[    0.167589] pci 0000:03:0e.0: [1283:8888] type 0 class 0x000601
[    0.167719] pci 0000:00:1e.0: PCI bridge to [bus 03-04] (subtractive decode)
[    0.167738] pci 0000:00:1e.0:   bridge window [io  0xf000-0x0000] (disabled)
[    0.167747] pci 0000:00:1e.0:   bridge window [mem 0xfff00000-0x000fffff] (disabled)
[    0.167760] pci 0000:00:1e.0:   bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[    0.167768] pci 0000:00:1e.0:   bridge window [io  0x0000-0xffff] (subtractive decode)
[    0.167776] pci 0000:00:1e.0:   bridge window [mem 0x00000000-0xffffffff] (subtractive decode)
[    0.167918] pci 0000:03:05.0: PCI bridge to [bus 04-04]
[    0.167938] pci 0000:03:05.0:   bridge window [io  0xf000-0x0000] (disabled)
[    0.167948] pci 0000:03:05.0:   bridge window [mem 0xfff00000-0x000fffff] (disabled)
[    0.167961] pci 0000:03:05.0:   bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[    0.168374] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.170715] pci 0000:00:1f.0: PIIX/ICH IRQ router [8086:27b9]
[    0.170754] PCI: pci_cache_line_size set to 64 bytes
[    0.170874] reserve RAM buffer: 0000000000097000 - 000000000009ffff 
[    0.170881] reserve RAM buffer: 000000001f7b0000 - 000000001fffffff 
[    0.171345] hpet clockevent registered
[    0.171379] Switching to clocksource hpet
[    0.171994] Switched to NOHz mode on CPU #0
[    0.175748] pci 0000:00:1c.0: PCI bridge to [bus 01-01]
[    0.175771] pci 0000:00:1c.0:   bridge window [io  0xd000-0xdfff]
[    0.175790] pci 0000:00:1c.0:   bridge window [mem 0xfea00000-0xfeafffff]
[    0.175808] pci 0000:00:1c.0:   bridge window [mem 0xfde00000-0xfdefffff 64bit pref]
[    0.175832] pci 0000:00:1c.1: PCI bridge to [bus 02-02]
[    0.175847] pci 0000:00:1c.1:   bridge window [io  0xe000-0xefff]
[    0.175865] pci 0000:00:1c.1:   bridge window [mem 0xfeb00000-0xfebfffff]
[    0.175883] pci 0000:00:1c.1:   bridge window [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.175908] pci 0000:03:05.0: PCI bridge to [bus 04-04]
[    0.175920] pci 0000:03:05.0:   bridge window [io  disabled]
[    0.175937] pci 0000:03:05.0:   bridge window [mem disabled]
[    0.175953] pci 0000:03:05.0:   bridge window [mem pref disabled]
[    0.175973] pci 0000:00:1e.0: PCI bridge to [bus 03-04]
[    0.175985] pci 0000:00:1e.0:   bridge window [io  disabled]
[    0.176035] pci 0000:00:1e.0:   bridge window [mem disabled]
[    0.176051] pci 0000:00:1e.0:   bridge window [mem pref disabled]
[    0.176092] pci 0000:00:1c.0: PCI->APIC IRQ transform: INT A -> IRQ 16
[    0.176111] pci 0000:00:1c.0: setting latency timer to 64
[    0.176129] pci 0000:00:1c.1: PCI->APIC IRQ transform: INT B -> IRQ 17
[    0.176147] pci 0000:00:1c.1: setting latency timer to 64
[    0.176161] pci 0000:00:1e.0: setting latency timer to 64
[    0.176179] pci_bus 0000:00: resource 0 [io  0x0000-0xffff]
[    0.176186] pci_bus 0000:00: resource 1 [mem 0x00000000-0xffffffff]
[    0.176193] pci_bus 0000:01: resource 0 [io  0xd000-0xdfff]
[    0.176200] pci_bus 0000:01: resource 1 [mem 0xfea00000-0xfeafffff]
[    0.176207] pci_bus 0000:01: resource 2 [mem 0xfde00000-0xfdefffff 64bit pref]
[    0.176214] pci_bus 0000:02: resource 0 [io  0xe000-0xefff]
[    0.176221] pci_bus 0000:02: resource 1 [mem 0xfeb00000-0xfebfffff]
[    0.176228] pci_bus 0000:02: resource 2 [mem 0xfdf00000-0xfdffffff 64bit pref]
[    0.176236] pci_bus 0000:03: resource 4 [io  0x0000-0xffff]
[    0.176242] pci_bus 0000:03: resource 5 [mem 0x00000000-0xffffffff]
[    0.176340] NET: Registered protocol family 2
[    0.176482] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.176894] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[    0.177031] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
[    0.177115] TCP: Hash tables configured (established 16384 bind 16384)
[    0.177129] TCP reno registered
[    0.177141] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.177158] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.177329] NET: Registered protocol family 1
[    0.177559] RPC: Registered udp transport module.
[    0.177574] RPC: Registered tcp transport module.
[    0.177585] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.177617] pci 0000:00:02.0: Boot video device
[    0.204075] PCI: CLS 32 bytes, default 64
[    0.204591] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.206578] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    0.206916] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.207212] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.207462] msgmni has been set to 984
[    0.207698] io scheduler noop registered
[    0.207712] io scheduler deadline registered
[    0.207757] io scheduler cfq registered (default)
[    0.208712] vesafb: framebuffer at 0xd0000000, mapped to 0xe0000000, using 1875k, total 7872k
[    0.208735] vesafb: mode is 800x600x16, linelength=1600, pages=7
[    0.208747] vesafb: scrolling: redraw
[    0.208760] vesafb: Truecolor: size=0:5:6:5, shift=0:11:5:0
[    0.208920] fbcon: VESA VGA (fb0) is primary device
[    0.218910] Console: switching to colour frame buffer device 100x37
[    0.228729] fb0: VESA VGA frame buffer device
[    0.315336] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.336203] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    0.357179] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[    0.359897] Real Time Clock Driver v1.12b
[    0.360285] Non-volatile memory driver v1.3
[    0.360455] Linux agpgart interface v0.103
[    0.360657] agpgart-intel 0000:00:00.0: Intel 945GME Chipset
[    0.361017] agpgart-intel 0000:00:00.0: detected gtt size: 262144K total, 262144K mappable
[    0.361413] agpgart-intel 0000:00:00.0: detected 8192K stolen memory
[    0.361968] agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0xd0000000
[    0.362498] [drm] Initialized drm 1.1.0 20060810
[    0.367492] pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 16
[    0.372431] pci 0000:00:02.0: setting latency timer to 64
[    0.415789] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[    0.417587] [drm] No driver support for vblank timestamp query.
[    0.419527] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0
[    0.425405] brd: module loaded
[    0.429340] loop: module loaded
[    0.431229] nbd: registered device at major 43
[    0.437444] Uniform Multi-Platform E-IDE driver
[    0.439550] piix 0000:00:1f.1: IDE controller (0x8086:0x27df rev 0x02)
[    0.441517] pci 0000:00:1f.1: PCI->APIC IRQ transform: INT A -> IRQ 18
[    0.443308] piix 0000:00:1f.1: IDE port disabled
[    0.445114] piix 0000:00:1f.1: not 100% native mode: will probe irqs later
[    0.446887]     ide0: BM-DMA at 0xffa0-0xffa7
[    0.448662] Probing IDE interface ide0...
[    1.016024] hdb: TS2GCF133, CFA DISK drive
[    1.017885] hdb: host max PIO4 wanted PIO255(auto-tune) selected PIO4
[    1.018274] hdb: host side 80-wire cable detection failed, limiting max speed to UDMA33
[    1.020106] hdb: UDMA/33 mode selected
[    1.022317] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[    1.024665] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
[    1.028416] ide-gd driver 1.18
[    1.030278] hdb: max request size: 128KiB
[    1.032137] hdb: 3915072 sectors (2004 MB) w/1KiB Cache, CHS=3884/16/63
[    1.040857]  hdb: hdb1 hdb2
[    1.043363] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    1.045292] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    1.047382] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[    1.049302] e100: Copyright(c) 1999-2006 Intel Corporation
[    1.051379] PPP generic driver version 2.4.2
[    1.053639] PPP Deflate Compression module registered
[    1.055518] PPP BSD Compression module registered
[    1.057582] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    1.059519] r8169 0000:01:00.0: PCI->APIC IRQ transform: INT A -> IRQ 16
[    1.061529] r8169 0000:01:00.0: setting latency timer to 64
[    1.061547] r8169 0000:01:00.0: no MSI. Back to INTx.
[    1.063927] r8169 0000:01:00.0: eth0: RTL8168cp/8111cp at 0xdffba000, 00:18:7d:15:07:03, XID 1cb00080 IRQ 16
[    1.067866] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    1.069880] r8169 0000:02:00.0: PCI->APIC IRQ transform: INT A -> IRQ 17
[    1.071895] r8169 0000:02:00.0: setting latency timer to 64
[    1.071912] r8169 0000:02:00.0: no MSI. Back to INTx.
[    1.074373] r8169 0000:02:00.0: eth1: RTL8168cp/8111cp at 0xdffda000, 00:18:7d:15:07:04, XID 1cb00080 IRQ 17
[    1.078425] netconsole: local port 5555
[    1.080391] netconsole: local IP 192.168.72.123
[    1.082337] netconsole: interface 'eth0'
[    1.084294] netconsole: remote port 5556
[    1.086204] netconsole: remote IP 192.168.72.255
[    1.088101] netconsole: remote ethernet address ff:ff:ff:ff:ff:ff
[    1.089976] netconsole: device eth0 not up yet, forcing it
[    1.094927] r8169 0000:01:00.0: eth0: link down
[    1.096790] r8169 0000:01:00.0: eth0: link down
[    1.204026] Refined TSC clocksource calibration: 1595.999 MHz.
[    1.205840] Switching to clocksource tsc
[    3.539171] r8169 0000:01:00.0: eth0: link up
[    3.884821] console [netcon0] enabled
[    3.886567] netconsole: network logging started
[    3.888317] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.890075] ehci_hcd 0000:00:1d.7: PCI->APIC IRQ transform: INT A -> IRQ 23
[    3.891828] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[    3.891837] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[    3.893585] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[    3.895370] ehci_hcd 0000:00:1d.7: using broken periodic workaround
[    3.897107] ehci_hcd 0000:00:1d.7: debug port 1
[    3.902667] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported
[    3.902686] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xfe93bc00
[    3.920022] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00
[    3.922218] hub 1-0:1.0: USB hub found
[    3.923874] hub 1-0:1.0: 8 ports detected
[    3.925702] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    3.927451] uhci_hcd: USB Universal Host Controller Interface driver
[    3.929187] uhci_hcd 0000:00:1d.0: PCI->APIC IRQ transform: INT A -> IRQ 23
[    3.930867] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[    3.930876] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[    3.932570] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    3.934266] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000cc00
[    3.936439] hub 2-0:1.0: USB hub found
[    3.938049] hub 2-0:1.0: 2 ports detected
[    3.939702] uhci_hcd 0000:00:1d.1: PCI->APIC IRQ transform: INT B -> IRQ 19
[    3.941351] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[    3.941360] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[    3.942945] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[    3.944581] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000c880
[    3.946624] hub 3-0:1.0: USB hub found
[    3.948195] hub 3-0:1.0: 2 ports detected
[    3.949813] uhci_hcd 0000:00:1d.2: PCI->APIC IRQ transform: INT C -> IRQ 18
[    3.951412] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[    3.951421] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[    3.953061] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[    3.954740] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000c800
[    3.956908] hub 4-0:1.0: USB hub found
[    3.958565] hub 4-0:1.0: 2 ports detected
[    3.960307] uhci_hcd 0000:00:1d.3: PCI->APIC IRQ transform: INT D -> IRQ 16
[    3.962003] uhci_hcd 0000:00:1d.3: setting latency timer to 64
[    3.962012] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[    3.963684] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[    3.965425] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000c480
[    3.967621] hub 5-0:1.0: USB hub found
[    3.969347] hub 5-0:1.0: 2 ports detected
[    3.971187] Initializing USB Mass Storage driver...
[    3.973018] usbcore: registered new interface driver usb-storage
[    3.974677] USB Mass Storage support registered.
[    3.976557] usbcore: registered new interface driver usbserial
[    3.978226] usbserial: USB Serial Driver core
[    3.980416] serio: i8042 KBD port at 0x60,0x64 irq 1
[    3.982181] serio: i8042 AUX port at 0x60,0x64 irq 12
[    3.984597] mousedev: PS/2 mouse device common for all mice
[    3.986752] input: PC Speaker as /devices/platform/pcspkr/input/input0
[    3.988739] lirc_dev: IR Remote Control driver registered, major 254 
[    3.993048] usbcore: registered new interface driver usbhid
[    3.994775] usbhid: USB HID core driver
[    3.997175] TCP cubic registered
[    3.998846] NET: Registered protocol family 17
[    4.000607] 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
[    4.002317] All bugs added by David S. Miller <davem@redhat.com>
[    4.004098] Using IPI Shortcut mode
[    4.083598] EXT3-fs: barriers not enabled
[    4.086056] EXT3-fs (hdb1): mounted filesystem with writeback data mode
[    4.087802] VFS: Mounted root (ext3 filesystem) readonly on device 3:65.
[    4.089553] kjournald starting.  Commit interval 5 seconds
[    4.091320] Freeing unused kernel memory: 324k freed
[    4.476078] usb 4-1: new low speed USB device using uhci_hcd and address 2
[    4.675868] input: MosArt Optical Mouse as /devices/pci0000:00/0000:00:1d.2/usb4/4-1/4-1:1.0/input/input1
[    4.680570] generic-usb 0003:062A:0003.0001: input: USB HID v1.10 Mouse [MosArt Optical Mouse] on usb-0000:00:1d.2-1/input0
[    4.924051] usb 4-2: new low speed USB device using uhci_hcd and address 3
[    5.121648] input: CHICONY USB Keyboard as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.0/input/input2
[    5.125991] generic-usb 0003:04F2:0116.0002: input: USB HID v1.10 Keyboard [CHICONY USB Keyboard] on usb-0000:00:1d.2-2/input0
[    5.475114] udevd (1187): /proc/1187/oom_adj is deprecated, please use /proc/1187/oom_score_adj instead.
[    8.034061] EXT3-fs: barriers not enabled
[    8.039110] kjournald starting.  Commit interval 5 seconds
[    8.042406] EXT3-fs (hdb2): using internal journal
[    8.044664] EXT3-fs (hdb2): recovery complete
[    8.046883] EXT3-fs (hdb2): mounted filesystem with ordered data mode
[    8.873029] r8169 0000:02:00.0: eth1: link down

# then X is started, and the rest is captured through netconsole
[  213.443542] BUG: unable to handle kernel  NULL pointer dereference  at 00000084
[  213.443566] IP:  [<c11f7e0f>] i915_driver_irq_handler+0x11f/0xa90
[  213.443587] *pde = 00000000  
[  213.443597] Oops: 0000 [#1]  PREEMPT  
[  213.443608] last sysfs file: /sys/devices/virtual/dmi/id/board_asset_tag
[  213.443617] Modules linked in: 
[  213.443626] 
[  213.443634] Pid: 2511, comm: ecu-ns-proxy Not tainted 2.6.38.7--NAVY #25   ICP / iEi PCISA-945GSE / PCISA-945GSE(B125) 
[  213.443657] EIP: 0060:[<c11f7e0f>] EFLAGS: 00210082 CPU: 0
[  213.443666] EIP is at i915_driver_irq_handler+0x11f/0xa90
[  213.443674] EAX: 00000000 EBX: 00000000 ECX: 00000000 EDX: dffee898
[  213.443682] ESI: 00000000 EDI: deffc000 EBP: dec09fc0 ESP: dec09f3c
[  213.443690]  DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 0068
[  213.443698] Process ecu-ns-proxy (pid: 2511, ti=dec08000 task=dec894e0 task.ti=dc592000)
[  213.443705] Stack:
[  213.443711]  00000002  c140f68a  c1398264  c1410474  de458400  dec09f64  c12348af  00011000 
[  213.443750]  de45c400  de458400  dec09f90  c123dc18  dec09f84  de4585bc  deffc2b8  deffc01c 

---- 8< ----

---- 8< ---- (X -verbose)
navy3:~# X -verbose
_XSERVTransSocketOpenCOTSServer: Unable to open socket for inet6
_XSERVTransOpen: transport open failed for inet6/navy3:0
_XSERVTransMakeAllCOTSServerListeners: failed to open listener for inet6

X.Org X Server 1.4.2
Release Date: 11 June 2008
X Protocol Version 11, Revision 0
Build Operating System: Linux Debian (xorg-server 2:1.4.2-10.lenny3)
Current Operating System: Linux navy3 2.6.38.7--NAVY #25 PREEMPT Sat May 28 13:39:47 MSD 2011 i686
Build Date: 25 September 2010  12:05:44PM
 
        Before reporting problems, check http://wiki.x.org
        to make sure that you have the latest version.
Module Loader present
Markers: (--) probed, (**) from config file, (==) default setting,
        (++) from command line, (!!) notice, (II) informational,
        (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
(==) Log file: "/var/log/Xorg.0.log", Time: Sat May 28 18:26:50 2011
(==) Using config file: "/etc/X11/xorg.conf"
(==) ServerLayout "Default Layout"
(**) |-->Screen "Default Screen" (0)
(**) |   |-->Monitor "LCD 1600x1200"
(==) No device specified for screen "Default Screen".
        Using the first device section listed.
(**) |   |-->Device "Default card"
(**) |-->Input Device "Generic Keyboard"
(**) |-->Input Device "Configured Mouse"
(==) Automatically adding devices
(==) Automatically enabling devices
(==) No FontPath specified.  Using compiled-in default.
(WW) The directory "/usr/share/fonts/X11/100dpi/" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/X11/75dpi/" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/X11/Type1" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/X11/100dpi" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/X11/75dpi" does not exist.
        Entry deleted from font path.
(WW) The directory "/var/lib/defoma/x-ttcidfont-conf.d/dirs/TrueType" does not exist.
        Entry deleted from font path.
(==) FontPath set to:
        /usr/share/fonts/X11/misc,
        /usr/share/fonts/X11/cyrillic
(==) RgbPath set to "/etc/X11/rgb"
(==) ModulePath set to "/usr/lib/xorg/modules"
(II) Loading /usr/lib/xorg/modules//libpcidata.so
(II) Module pcidata: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(--) using VT number 7

(--) PCI:*(0:2:0) Intel Corporation Mobile 945GME Express Integrated Graphics Controller rev 3, Mem @ 0xfe980000/19, 0xd0000000/28, 0xfe940000/18, I/O @ 0xcc80/3
(--) PCI: (0:2:1) Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller rev 3, Mem @ 0xfe880000/19
(II) "extmod" will be loaded. This was enabled by default and also specified in the config file.
(II) "dbe" will be loaded. This was enabled by default and also specified in the config file.
(II) "glx" will be loaded by default.
(II) "freetype" will be loaded. This was enabled by default and also specified in the config file.
(II) "record" will be loaded. This was enabled by default and also specified in the config file.
(II) "dri" will be loaded by default.
(II) Loading /usr/lib/xorg/modules/extensions//libdbe.so
(II) Module dbe: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(II) Module "ddc" already built-in
(II) Loading /usr/lib/xorg/modules/extensions//libextmod.so
(II) Module extmod: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(II) Loading /usr/lib/xorg/modules//fonts/libfreetype.so
(II) Module freetype: vendor="X.Org Foundation & the After X-TT Project"
        compiled for 1.4.2, module version = 2.1.0
(II) Loading /usr/lib/xorg/modules//libint10.so
(II) Module int10: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(II) Loading /usr/lib/xorg/modules/extensions//librecord.so
(II) Module record: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.13.0
(II) Loading /usr/lib/xorg/modules//libvbe.so
(II) Module vbe: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.1.0
(II) Loading /usr/lib/xorg/modules/drivers//v4l_drv.so
(II) Module v4l: vendor="X.Org Foundation"
        compiled for 1.4.0.90, module version = 0.1.1
(II) Loading /usr/lib/xorg/modules/extensions//libglx.so
(II) Module glx: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(==) AIGLX enabled
(II) Loading /usr/lib/xorg/modules/extensions//libdri.so
(II) Module dri: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(II) Matched intel from file name intel.ids in autoconfig
(==) Matched intel for the autoconfigured driver
(==) Assigned the driver to the xf86ConfigLayout
(II) Loading /usr/lib/xorg/modules/drivers//intel_drv.so
(II) Module intel: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 2.3.2
(II) Loading /usr/lib/xorg/modules/input//kbd_drv.so
(II) Module kbd: vendor="X.Org Foundation"
        compiled for 1.4.0.90, module version = 1.3.1
(II) Loading /usr/lib/xorg/modules/input//mouse_drv.so
(II) Module mouse: vendor="X.Org Foundation"
        compiled for 1.4.0.90, module version = 1.3.0
(II) v4l driver for Video4Linux
(II) intel: Driver for Intel Integrated Graphics Chipsets: i810,
        i810-dc100, i810e, i815, i830M, 845G, 852GM/855GM, 865G, 915G,
        E7221 (i915), 915GM, 945G, 945GM, 945GME, 965G, G35, 965Q, 946GZ,
        965GM, 965GME/GLE, G33, Q35, Q33,
        Mobile Intel® GM45 Express Chipset,
        Intel Integrated Graphics Device, G45/G43, Q45/Q43, G41
(--) Assigning device section with no busID to primary device
(WW) intel: No matching Device section for instance (BusID PCI:0:2:1) found
(--) Chipset 945GME found
(II) Loading /usr/lib/xorg/modules//libvgahw.so
(II) Module vgahw: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 0.1.0
(**) intel(0): Depth 16, (--) framebuffer bpp 16
(==) intel(0): RGB weight 565
(==) intel(0): Default visual is TrueColor
(II) intel(0): Integrated Graphics Chipset: Intel(R) 945GME
(--) intel(0): Chipset: "945GME"
(--) intel(0): Linear framebuffer at 0xD0000000
(--) intel(0): IO registers at addr 0xFE980000
(II) intel(0): 2 display pipes available.
(**) intel(0): Using XAA for acceleration
(II) Module "ddc" already built-in
(II) Module "i2c" already built-in
(II) intel(0): Output VGA using monitor section LCD 1600x1200
(II) intel(0): I2C bus "CRTDDC_A" initialized.
(II) intel(0): Output LVDS has no monitor section
(II) intel(0): I2C bus "LVDSDDC_C" initialized.
(II) intel(0): Attempting to determine panel fixed mode.
(II) intel(0): I2C device "LVDSDDC_C:ddc2" registered at address 0xA0.
(II) intel(0): I2C device "LVDSDDC_C:ddc2" removed.
(II) intel(0): initializing int10
(WW) intel(0): Bad V_BIOS checksum
(II) intel(0): Primary V_BIOS segment is: 0xc000
(II) intel(0): VESA BIOS detected
(II) intel(0): I2C bus "SDVOCTRL_E for SDVOB" initialized.
(II) intel(0): I2C device "SDVOCTRL_E for SDVOB:SDVO Controller B" registered at address 0x70.
(II) intel(0): No SDVO device found on SDVOB
(II) intel(0): I2C device "SDVOCTRL_E for SDVOB:SDVO Controller B" removed.
(II) intel(0): I2C bus "SDVOCTRL_E for SDVOB" removed.
(II) intel(0): I2C bus "SDVOCTRL_E for SDVOC" initialized.
(II) intel(0): I2C device "SDVOCTRL_E for SDVOC:SDVO Controller C" registered at address 0x72.
(II) intel(0): No SDVO device found on SDVOC
(II) intel(0): I2C device "SDVOCTRL_E for SDVOC:SDVO Controller C" removed.
(II) intel(0): I2C bus "SDVOCTRL_E for SDVOC" removed.
(II) intel(0): Output TV has no monitor section
(II) intel(0): I2C device "CRTDDC_A:ddc2" registered at address 0xA0.
(II) intel(0): EDID vendor "SAM", prod id 476
(II) intel(0): Using hsync ranges from config file
(II) intel(0): Using vrefresh ranges from config file
(II) intel(0): Printing DDC gathered Modelines:
(II) intel(0): Modeline "1280x1024"x0.0  108.00  1280 1328 1440 1688  1024 1025 1028 1066 +hsync +vsync (64.0 kHz)
(II) intel(0): Modeline "800x600"x0.0   40.00  800 840 968 1056  600 601 605 628 +hsync +vsync (37.9 kHz)
(II) intel(0): Modeline "800x600"x0.0   36.00  800 824 896 1024  600 601 603 625 +hsync +vsync (35.2 kHz)
(II) intel(0): Modeline "640x480"x0.0   31.50  640 656 720 840  480 481 484 500 -hsync -vsync (37.5 kHz)
(II) intel(0): Modeline "640x480"x0.0   31.50  640 664 704 832  480 489 491 520 -hsync -vsync (37.9 kHz)
(II) intel(0): Modeline "640x480"x0.0   30.24  640 704 768 864  480 483 486 525 -hsync -vsync (35.0 kHz)
(II) intel(0): Modeline "640x480"x0.0   25.20  640 656 752 800  480 490 492 525 -hsync -vsync (31.5 kHz)
(II) intel(0): Modeline "720x400"x0.0   28.32  720 738 846 900  400 412 414 449 -hsync +vsync (31.5 kHz)
(II) intel(0): Modeline "1280x1024"x0.0  135.00  1280 1296 1440 1688  1024 1025 1028 1066 +hsync +vsync (80.0 kHz)
(II) intel(0): Modeline "1024x768"x0.0   78.80  1024 1040 1136 1312  768 769 772 800 +hsync +vsync (60.1 kHz)
(II) intel(0): Modeline "1024x768"x0.0   75.00  1024 1048 1184 1328  768 771 777 806 -hsync -vsync (56.5 kHz)
(II) intel(0): Modeline "1024x768"x0.0   65.00  1024 1048 1184 1344  768 771 777 806 -hsync -vsync (48.4 kHz)
(II) intel(0): Modeline "832x624"x0.0   57.28  832 864 928 1152  624 625 628 667 -hsync -vsync (49.7 kHz)
(II) intel(0): Modeline "800x600"x0.0   49.50  800 816 896 1056  600 601 604 625 +hsync +vsync (46.9 kHz)
(II) intel(0): Modeline "800x600"x0.0   50.00  800 856 976 1040  600 637 643 666 +hsync +vsync (48.1 kHz)
(II) intel(0): Modeline "1152x864"x0.0  108.00  1152 1216 1344 1600  864 865 868 900 +hsync +vsync (67.5 kHz)
(II) intel(0): Modeline "1280x1024"x59.9  109.00  1280 1368 1496 1712  1024 1027 1034 1063 -hsync +vsync (63.7 kHz)
(II) intel(0): Modeline "1280x960"x59.9  101.25  1280 1360 1488 1696  960 963 967 996 -hsync +vsync (59.7 kHz)
(II) intel(0): Modeline "1152x864"x74.8  104.00  1152 1224 1344 1536  864 867 871 905 -hsync +vsync (67.7 kHz)
(II) intel(0): EDID vendor "SAM", prod id 476
(II) intel(0): I2C device "LVDSDDC_C:ddc2" registered at address 0xA0.
(II) intel(0): I2C device "LVDSDDC_C:ddc2" removed.
(II) intel(0): Output VGA connected
(II) intel(0): Output LVDS connected
(II) intel(0): Output TV disconnected
(II) intel(0): Output VGA using initial mode 1280x1024
(II) intel(0): Output LVDS using initial mode 800x600
(II) intel(0): Monitoring connected displays enabled
(II) intel(0): detected 256 kB GTT.
(II) intel(0): detected 7932 kB stolen memory.
(==) intel(0): video overlay key set to 0x83e
(==) intel(0): Will not try to enable page flipping
(==) intel(0): Triple buffering disabled
(==) intel(0): Intel XvMC decoder disabled
(==) intel(0): Using gamma correction (1.0, 1.0, 1.0)
(**) intel(0): Display dimensions: (340, 270) mm
(**) intel(0): DPI set to (119, 150)
(II) Loading /usr/lib/xorg/modules//libfb.so
(II) Module fb: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.0.0
(II) Loading /usr/lib/xorg/modules//libxaa.so
(II) Module xaa: vendor="X.Org Foundation"
        compiled for 1.4.2, module version = 1.2.0
(II) Module "ramdac" already built-in
(II) intel(0): Comparing regs from server start up to After PreInit
(WW) intel(0): Register 0x61200 (PP_STATUS) changed from 0xc0000008 to 0xd000000a
(WW) intel(0): PP_STATUS before: on, ready, sequencing idle
(WW) intel(0): PP_STATUS after: on, ready, sequencing on
(WW) intel(0): Register 0x61114 (PORT_HOTPLUG_STAT) changed from 0x00000b00 to 0x00000f00
(WW) intel(0): Register 0x68000 (TV_CTL) changed from 0x10000010 to 0x000c0010
(WW) intel(0): Register 0x68010 (TV_CSC_Y) changed from 0x00000000 to 0x0332012d
(WW) intel(0): Register 0x68014 (TV_CSC_Y2) changed from 0x00000000 to 0x07d30104
(WW) intel(0): Register 0x68018 (TV_CSC_U) changed from 0x00000000 to 0x0733052d
(WW) intel(0): Register 0x6801c (TV_CSC_U2) changed from 0x00000000 to 0x05c70200
(WW) intel(0): Register 0x68020 (TV_CSC_V) changed from 0x00000000 to 0x0340030c
(WW) intel(0): Register 0x68024 (TV_CSC_V2) changed from 0x00000000 to 0x06d00200
(WW) intel(0): Register 0x68028 (TV_CLR_KNOBS) changed from 0x00000000 to 0x00606000
(WW) intel(0): Register 0x6802c (TV_CLR_LEVEL) changed from 0x00000000 to 0x010b00e1
(WW) intel(0): Register 0x68030 (TV_H_CTL_1) changed from 0x00000000 to 0x00400359
(WW) intel(0): Register 0x68034 (TV_H_CTL_2) changed from 0x00000000 to 0x80480022
(WW) intel(0): Register 0x68038 (TV_H_CTL_3) changed from 0x00000000 to 0x007c0344
(WW) intel(0): Register 0x6803c (TV_V_CTL_1) changed from 0x00000000 to 0x00f01415
(WW) intel(0): Register 0x68040 (TV_V_CTL_2) changed from 0x00000000 to 0x00060607
(WW) intel(0): Register 0x68044 (TV_V_CTL_3) changed from 0x00000000 to 0x80120001
(WW) intel(0): Register 0x68048 (TV_V_CTL_4) changed from 0x00000000 to 0x000900f0
(WW) intel(0): Register 0x6804c (TV_V_CTL_5) changed from 0x00000000 to 0x000a00f0
(WW) intel(0): Register 0x68050 (TV_V_CTL_6) changed from 0x00000000 to 0x000900f0
(WW) intel(0): Register 0x68054 (TV_V_CTL_7) changed from 0x00000000 to 0x000a00f0
(WW) intel(0): Register 0x68060 (TV_SC_CTL_1) changed from 0x00000000 to 0xc1710088
(WW) intel(0): Register 0x68064 (TV_SC_CTL_2) changed from 0x00000000 to 0x4e2d1dc8
(WW) intel(0): Register 0x68070 (TV_WIN_POS) changed from 0x00000000 to 0x00360024
(WW) intel(0): Register 0x68074 (TV_WIN_SIZE) changed from 0x00000000 to 0x02640198
(WW) intel(0): Register 0x68080 (TV_FILTER_CTL_1) changed from 0x00000000 to 0x800010bb
(WW) intel(0): Register 0x68084 (TV_FILTER_CTL_2) changed from 0x00000000 to 0x00028283
(WW) intel(0): Register 0x68088 (TV_FILTER_CTL_3) changed from 0x00000000 to 0x00014141
(WW) intel(0): Register 0x68100 (TV_H_LUMA_0) changed from 0x00000000 to 0xb1403000
(WW) intel(0): Register 0x681ec (TV_H_LUMA_59) changed from 0x00000000 to 0x0000b060
(WW) intel(0): Register 0x68200 (TV_H_CHROMA_0) changed from 0x00000000 to 0xb1403000
(WW) intel(0): Register 0x682ec (TV_H_CHROMA_59) changed from 0x00000000 to 0x0000b060
(II) intel(0): Kernel reported 107776 total, 0 used
(II) [drm] DRM interface version 1.4
(II) [drm] DRM open master succeeded.
(II) intel(0): [drm] Using the DRM lock SAREA also for drawables.
(II) intel(0): [drm] framebuffer mapped by ddx driver
(II) intel(0): [drm] added 1 reserved context for kernel
(II) intel(0): X context handle = 0x1
(II) intel(0): [drm] installed DRM signal handler
(**) intel(0): Framebuffer compression enabled
(**) intel(0): Tiling enabled
(==) intel(0): VideoRam: 262144 KB
(II) intel(0): Attempting memory allocation with tiled buffers.
(II) intel(0): Allocating 4800 scanlines for pixmap cache
(II) intel(0): Tiled allocation successful.
(II) intel(0): [drm] Registers = 0xfe980000
(II) intel(0): [drm] ring buffer = 0xd0000000
(II) intel(0): [drm] mapped front buffer at 0xd2000000, handle = 0xd2000000
(II) intel(0): [drm] mapped back buffer at 0xd0800000, handle = 0xd0800000
(II) intel(0): [drm] mapped depth buffer at 0xd1000000, handle = 0xd1000000
(II) intel(0): [drm] mapped classic textures at 0xd4000000, handle = 0xd4000000
(II) intel(0): [drm] Initialized kernel agp heap manager, 33554432
(II) intel(0): [dri] visual configs initialized
(II) intel(0): Page Flipping disabled
(==) intel(0): Write-combining range (0xd0000000,0x10000000)
(II) intel(0): Using XFree86 Acceleration Architecture (XAA)
        Screen to screen bit blits
        Solid filled rectangles
        8x8 mono pattern filled rectangles
        Indirect CPU to Screen color expansion
        Solid Horizontal and Vertical Lines
        Setting up tile and stipple cache:
                32 128x128 slots
                32 256x256 slots
                16 512x512 slots
(==) intel(0): Backing store disabled
(==) intel(0): Silken mouse enabled
(II) intel(0): Initializing HW Cursor
(II) intel(0): [DRI] installation complete
(II) intel(0): Fixed memory allocation layout:
(II) intel(0): 0x00000000-0x0001ffff: ring buffer (128 kB)
(II) intel(0): 0x00020000-0x0061ffff: compressed frame buffer (6144 kB, 0x000000001f820000 physical
)
(II) intel(0): 0x00620000-0x00620fff: compressed ll buffer (4 kB, 0x000000001fe20000 physical
)
(II) intel(0): 0x00621000-0x0062afff: HW cursors (40 kB, 0x000000001fe21000 physical
)
(II) intel(0): 0x0062b000-0x00632fff: logical 3D context (32 kB)
(II) intel(0): 0x00633000-0x00633fff: overlay registers (4 kB, 0x000000001fe33000 physical
)
(II) intel(0): 0x00634000-0x00643fff: xaa scratch (64 kB)
(II) intel(0): 0x007bf000:            end of stolen memory
(II) intel(0): 0x00800000-0x00ffffff: back buffer (6400 kB) X tiled
(II) intel(0): 0x01000000-0x017fffff: depth buffer (6400 kB) X tiled
(II) intel(0): 0x02000000-0x03ffffff: front buffer (25600 kB) X tiled
(II) intel(0): 0x04000000-0x05ffffff: classic textures (32768 kB)
(II) intel(0): 0x10000000:            end of aperture
(II) intel(0): Selecting standard 18 bit TMDS pixel format.
(II) intel(0): Output configuration:
(II) intel(0):   Pipe A is on
(II) intel(0):   Display plane A is now enabled and connected to pipe A.
(II) intel(0):   Pipe B is on
(II) intel(0):   Display plane B is now enabled and connected to pipe B.
(II) intel(0):   Output VGA is connected to pipe A
(II) intel(0):   Output LVDS is connected to pipe B
(II) intel(0):   Output TV is connected to pipe none
(II) intel(0): [drm] dma control initialized, using IRQ 16
(II) intel(0): RandR 1.2 enabled, ignore the following RandR disabled message.

# lockup here

---- 8< ----

Comment 1 Kirill Smelkov 2011-05-28 10:32:56 UTC

First-Bad-Commit : e8616b6ced6137085e6657cc63bc2fe3900b8616

Comment 2 Kirill Smelkov 2011-05-28 10:33:32 UTC

With v2.6.39 and the same userspace setup it does not panic, but X refuses to
start at all:

    ...
    (II) intel(0): Selecting standard 18 bit TMDS pixel format.
    (II) intel(0): Output configuration:
    (II) intel(0):   Pipe A is on
    (II) intel(0):   Display plane A is now enabled and connected to pipe A.
    (II) intel(0):   Pipe B is on
    (II) intel(0):   Display plane B is now enabled and connected to pipe B.
    (II) intel(0):   Output VGA is connected to pipe A
    (II) intel(0):   Output LVDS is connected to pipe B
    (II) intel(0):   Output TV is connected to pipe none
    (EE) intel(0): [drm] failure adding irq handler
    (II) intel(0): [drm] removed 1 reserved context for kernel
    (II) intel(0): [drm] unmapping 8192 bytes of SAREA 0xdfff7000 at 0xb7252000
    (II) intel(0): [drm] Closed DRM master.

    Fatal server error:
    AddScreen/ScreenInit failed for driver 0

with no messages in dmesg.

Comment 3 Florian Mickler 2011-06-26 13:54:49 UTC

Does the system work, if you revert the bad commit on top of a current kernel?

Comment 4 Kirill Smelkov 2011-06-29 10:39:42 UTC

Yes, if I revert e8616b6ced6137085e6657cc63bc2fe3900b8616 I'm getting this behaviour:

2.6.38.7                 BUG/NULL in i915_driver_irq_handler
2.6.38.7+revert          works OK

2.6.39.2                 X does not start at all (AddScreen/ScreenInit failed)
                         -- see comment2
2.6.39.2+revert          X does not start at all, like with 2.6.39.2

v3.0-rc5-63-g0d72c6f     works, but when X starts, there are lots of
                         "Bad IO access at port 0x84" (*)
v3.0-rc5-63-g0d72c6f+revert
                         works OK, without bad_io_access.


So it seems while we can't make 2.6.39 to work with legacy X through reverting bad commit, both 2.6.38 and 3.0 benefit from reverting.

Attaching dmesg log for 3.0-rc5 without revert and the revert patch itself.


Thanks for asking,
Kirill


(*) dmesg for v3.0-rc5-63-g0d72c6f

# normal dmesg here
# then `X -verbose` starts
[   30.580169] ------------[ cut here ]------------
[   30.580195] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   30.580204] Hardware name: PCISA-945GSE
[   30.580210] Bad IO access at port 0x84 (return inl(port))
[   30.580217] Modules linked in:
[   30.580230] Pid: 2820, comm: sshd Not tainted 3.0.0-rc5--NAVY-00064-g30846ee #30
[   30.580238] Call Trace:
[   30.580250]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.580263]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   30.580274]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.580285]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   30.580295]  [<c118e56d>] bad_io_access+0x3d/0x40
[   30.580306]  [<c118e922>] ioread32+0x22/0x40
[   30.580318]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   30.580330]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   30.580342]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   30.580354]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   30.580364]  [<c105c259>] handle_irq_event+0x39/0x60
[   30.580375]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   30.580382]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   30.580403]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   30.580416]  [<c10a8fa7>] ? core_sys_select+0x87/0x260
[   30.580427]  [<c131cff3>] ? sock_aio_write+0x103/0x150
[   30.580438]  [<c1025b19>] ? __wake_up+0x49/0x70
[   30.580450]  [<c1097e11>] ? do_sync_write+0xb1/0xf0
[   30.580463]  [<c10467c1>] ? __srcu_read_unlock+0x31/0x60
[   30.580484]  [<c10c7deb>] ? fsnotify+0xeb/0x2a0
[   30.580496]  [<c1098902>] ? vfs_write+0x102/0x140
[   30.580506]  [<c10a932c>] ? sys_select+0x2c/0xb0
[   30.580516]  [<c13bb750>] ? sysenter_do_call+0x12/0x26
[   30.580526] ---[ end trace 30b693cd8fbac755 ]---
[   30.612479] ------------[ cut here ]------------
[   30.612505] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   30.612513] Hardware name: PCISA-945GSE
[   30.612520] Bad IO access at port 0x84 (return inl(port))
[   30.612526] Modules linked in:
[   30.612539] Pid: 2820, comm: sshd Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   30.612547] Call Trace:
[   30.612560]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.612572]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   30.612583]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.612594]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   30.612604]  [<c118e56d>] bad_io_access+0x3d/0x40
[   30.612615]  [<c118e922>] ioread32+0x22/0x40
[   30.612627]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   30.612638]  [<c10459d8>] ? hrtimer_interrupt+0x198/0x340
[   30.612651]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   30.612663]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   30.612675]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   30.612685]  [<c105c259>] handle_irq_event+0x39/0x60
[   30.612696]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   30.612703]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   30.612723]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   30.612734]  [<c109007b>] ? vm_map_ram+0x3cb/0x4f0
[   30.612745]  [<c10a9300>] ? sys_pselect6+0x180/0x180
[   30.612755]  [<c13bb750>] ? sysenter_do_call+0x12/0x26
[   30.612764] ---[ end trace 30b693cd8fbac756 ]---
[   30.613700] ------------[ cut here ]------------
[   30.613717] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   30.613724] Hardware name: PCISA-945GSE
[   30.613731] Bad IO access at port 0x84 (return inl(port))
[   30.613737] Modules linked in:
[   30.613749] Pid: 0, comm: swapper Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   30.613756] Call Trace:
[   30.613767]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.613778]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   30.613788]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.613799]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   30.613809]  [<c118e56d>] bad_io_access+0x3d/0x40
[   30.613820]  [<c118e922>] ioread32+0x22/0x40
[   30.613830]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   30.613841]  [<c10459d8>] ? hrtimer_interrupt+0x198/0x340
[   30.613853]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   30.613865]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   30.613876]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   30.613886]  [<c105c259>] handle_irq_event+0x39/0x60
[   30.613897]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   30.613904]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   30.613921]  [<c1047a90>] ? sched_clock_local+0xe0/0x180
[   30.613933]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   30.613945]  [<c104007b>] ? clock_nanosleep_restart+0x1b/0xa0
[   30.613957]  [<c11d9010>] ? acpi_idle_enter_bm+0x272/0x29e
[   30.613969]  [<c130997d>] ? cpuidle_idle_call+0x8d/0xd0
[   30.613978]  [<c1001bec>] ? cpu_idle+0x4c/0x80
[   30.613989]  [<c13a8a7a>] ? rest_init+0x7a/0x80
[   30.613999]  [<c150b80d>] ? start_kernel+0x2c4/0x327
[   30.614010]  [<c150b30d>] ? kernel_init+0x126/0x126
[   30.614020]  [<c150b079>] ? i386_start_kernel+0x79/0xb5
[   30.614028] ---[ end trace 30b693cd8fbac757 ]---
[   30.833527] ------------[ cut here ]------------
[   30.833550] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   30.833558] Hardware name: PCISA-945GSE
[   30.833564] Bad IO access at port 0x84 (return inl(port))
[   30.833570] Modules linked in:
[   30.833583] Pid: 2830, comm: Xorg Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   30.833591] Call Trace:
[   30.833602]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.833615]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   30.833625]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   30.833636]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   30.833646]  [<c118e56d>] bad_io_access+0x3d/0x40
[   30.833657]  [<c118e922>] ioread32+0x22/0x40
[   30.833668]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   30.833680]  [<c10459d8>] ? hrtimer_interrupt+0x198/0x340
[   30.833691]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   30.833704]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   30.833715]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   30.833725]  [<c105c259>] handle_irq_event+0x39/0x60
[   30.833736]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   30.833743]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   30.833761]  [<c102dd42>] ? irq_exit+0x62/0xb0
[   30.833772]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   30.833784]  [<c13b0000>] ? tg3_issue_otp_command+0x27/0x59
[   30.833793] ---[ end trace 30b693cd8fbac758 ]---
[   31.050160] ------------[ cut here ]------------
[   31.050187] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.050195] Hardware name: PCISA-945GSE
[   31.050202] Bad IO access at port 0x84 (return inl(port))
[   31.050209] Modules linked in:
[   31.050222] Pid: 2820, comm: sshd Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.050230] Call Trace:
[   31.050243]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.050255]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.050266]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.050276]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.050287]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.050297]  [<c118e922>] ioread32+0x22/0x40
[   31.050309]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.050321]  [<c10459d8>] ? hrtimer_interrupt+0x198/0x340
[   31.050333]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.050346]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.050357]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.050368]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.050378]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.050385]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.050404]  [<c10989fd>] ? sys_write+0x3d/0x70
[   31.050416]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.050425] ---[ end trace 30b693cd8fbac759 ]---
[   31.105482] ------------[ cut here ]------------
[   31.105512] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.105521] Hardware name: PCISA-945GSE
[   31.105528] Bad IO access at port 0x84 (return inl(port))
[   31.105535] Modules linked in:
[   31.105549] Pid: 2761, comm: ecu-ns-proxy Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.105558] Call Trace:
[   31.105571]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.105584]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.105594]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.105605]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.105616]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.105626]  [<c118e922>] ioread32+0x22/0x40
[   31.105639]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.105651]  [<c104fb09>] ? clockevents_program_event+0x99/0x150
[   31.105663]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.105675]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.105687]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.105698]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.105708]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.105716]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.105736]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.105745] ---[ end trace 30b693cd8fbac75a ]---
[   31.144033] ------------[ cut here ]------------
[   31.144069] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.144078] Hardware name: PCISA-945GSE
[   31.144085] Bad IO access at port 0x84 (return inl(port))
[   31.144092] Modules linked in:
[   31.144106] Pid: 2830, comm: Xorg Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.144115] Call Trace:
[   31.144128]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.144141]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.144152]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.144163]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.144174]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.144184]  [<c118e922>] ioread32+0x22/0x40
[   31.144197]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.144209]  [<c104fb09>] ? clockevents_program_event+0x99/0x150
[   31.144222]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.144234]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.144246]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.144257]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.144267]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.144274]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.144293]  [<c117d2af>] ? cfq_idle_slice_timer+0x4f/0xc0
[   31.144304]  [<c102de10>] ? __local_bh_enable+0x70/0x70
[   31.144317]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.144327]  [<c102de10>] ? __local_bh_enable+0x70/0x70
[   31.144338]  [<c102de4d>] ? __do_softirq+0x3d/0x120
[   31.144348]  [<c102de10>] ? __local_bh_enable+0x70/0x70
[   31.144355]  <IRQ>   [<c102dd85>] ? irq_exit+0xa5/0xb0
[   31.144373]  [<c101759b>] ? smp_apic_timer_interrupt+0x4b/0x80
[   31.144384]  [<c13bb332>] ? apic_timer_interrupt+0x2a/0x30
[   31.144396]  [<c13b0000>] ? tg3_issue_otp_command+0x27/0x59
[   31.144405] ---[ end trace 30b693cd8fbac75b ]---
[   31.151184] ------------[ cut here ]------------
[   31.151209] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.151218] Hardware name: PCISA-945GSE
[   31.151225] Bad IO access at port 0x84 (return inl(port))
[   31.151231] Modules linked in:
[   31.151243] Pid: 2820, comm: sshd Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.151252] Call Trace:
[   31.151264]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.151276]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.151287]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.151298]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.151308]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.151318]  [<c118e922>] ioread32+0x22/0x40
[   31.151330]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.151342]  [<c104fb09>] ? clockevents_program_event+0x99/0x150
[   31.151355]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.151367]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.151378]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.151388]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.151399]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.151406]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.151425]  [<c10989fd>] ? sys_write+0x3d/0x70
[   31.151436]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.151446] ---[ end trace 30b693cd8fbac75c ]---
[   31.424504] ------------[ cut here ]------------
[   31.424538] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.424547] Hardware name: PCISA-945GSE
[   31.424554] Bad IO access at port 0x84 (return inl(port))
[   31.424561] Modules linked in:
[   31.424575] Pid: 2835, comm: xkbcomp Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.424583] Call Trace:
[   31.424596]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.424609]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.424620]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.424630]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.424641]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.424652]  [<c118e922>] ioread32+0x22/0x40
[   31.424664]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.424677]  [<c104fb09>] ? clockevents_program_event+0x99/0x150
[   31.424689]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.424702]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.424714]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.424724]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.424735]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.424742]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.424760]  [<c1098c2d>] ? sys_read+0x3d/0x70
[   31.424772]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.424782] ---[ end trace 30b693cd8fbac75d ]---
[   31.534317] ------------[ cut here ]------------
[   31.534346] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
[   31.534355] Hardware name: PCISA-945GSE
[   31.534362] Bad IO access at port 0x84 (return inl(port))
[   31.534369] Modules linked in:
[   31.534383] Pid: 2820, comm: sshd Tainted: G        W   3.0.0-rc5--NAVY-00064-g30846ee #30
[   31.534391] Call Trace:
[   31.534404]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.534417]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
[   31.534427]  [<c118e56d>] ? bad_io_access+0x3d/0x40
[   31.534438]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
[   31.534449]  [<c118e56d>] bad_io_access+0x3d/0x40
[   31.534459]  [<c118e922>] ioread32+0x22/0x40
[   31.534472]  [<c122c0dd>] i915_driver_irq_handler+0x1ad/0x660
[   31.534485]  [<c104fb09>] ? clockevents_program_event+0x99/0x150
[   31.534497]  [<c12c640e>] ? rtl8169_interrupt+0xee/0x370
[   31.534509]  [<c105c116>] handle_irq_event_percpu+0x36/0x140
[   31.534520]  [<c105e210>] ? handle_edge_irq+0x150/0x150
[   31.534531]  [<c105c259>] handle_irq_event+0x39/0x60
[   31.534542]  [<c105e255>] handle_fasteoi_irq+0x45/0xd0
[   31.534549]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
[   31.534567]  [<c10989fd>] ? sys_write+0x3d/0x70
[   31.534579]  [<c13bbc69>] ? common_interrupt+0x29/0x30
[   31.534589] ---[ end trace 30b693cd8fbac75e ]---

# Here X is started and works without further messages in dmesg about
# bad io access (tested for 5 minutes)

Comment 5 Kirill Smelkov 2011-06-29 10:42:07 UTC

Created attachment 63862 [details]
0001-Revert-drm-i915-Initialise-ring-vfuncs-for-old-DRI-p.patch

Comment 6 Kirill Smelkov 2011-07-22 11:15:15 UTC

Problem still present in v3.0

    On netconsole:

    # X starts here, then

    [   45.102377] ------------[ cut here ]------------
    [   45.102402] WARNING: at lib/iomap.c:43 bad_io_access+0x3d/0x40()
    [   45.102411] Hardware name: PCISA-945GSE
    [   45.102418] Bad IO access at port 0x84 (return inl(port))
    [   45.102425] Modules linked in:
    [   45.102438] Pid: 2846, comm: sshd Not tainted 3.0.0--NAVY #33
    [   45.102445] Call Trace:
    [   45.102460]  [<c118e9fd>] ? bad_io_access+0x3d/0x40
    [   45.102473]  [<c10287ec>] warn_slowpath_common+0x6c/0xa0
    [   45.102484]  [<c118e9fd>] ? bad_io_access+0x3d/0x40
    [   45.102495]  [<c102889e>] warn_slowpath_fmt+0x2e/0x30
    [   45.102506]  [<c118e9fd>] bad_io_access+0x3d/0x40
    [   45.102516]  [<c118edb2>] ioread32+0x22/0x40
    [   45.102528]  [<c122cc7d>] i915_driver_irq_handler+0x1ad/0x660
    [   45.102541]  [<c12c6a7e>] ? rtl8169_interrupt+0xee/0x370
    [   45.102554]  [<c105c396>] handle_irq_event_percpu+0x36/0x140
    [   45.102565]  [<c105e490>] ? handle_edge_irq+0x150/0x150
    [   45.102576]  [<c105c4d9>] handle_irq_event+0x39/0x60
    [   45.102587]  [<c105e4d5>] handle_fasteoi_irq+0x45/0xd0
    [   45.102594]  <IRQ>   [<c1003c29>] ? do_IRQ+0x39/0xb0
    [   45.102613]  [<c103c9b3>] ? start_flush_work+0xc3/0x130
    [   45.102625]  [<c13bc329>] ? common_interrupt+0x29/0x30
    [   45.102636]  [<c13bc329>] ? common_interrupt+0x29/0x30
    [   45.102648]  [<c11e007b>] ? pnpacpi_encode_resources+0x37b/0x7a0
    [   45.102659]  [<c109971e>] ? fget_light+0xe/0xf0
    [   45.102671]  [<c10a8f97>] ? do_select+0x2e7/0x680
    [   45.102685]  [<c1341998>] ? sch_direct_xmit+0x58/0x1d0
    [   45.102695]  [<c10a83e0>] ? poll_freewait+0xa0/0xa0
    [   45.102706]  [<c102df37>] ? local_bh_enable+0x47/0xa0
    [   45.102718]  [<c132e371>] ? dev_queue_xmit+0x101/0x4e0
    [   45.102729]  [<c134ffba>] ? ip_finish_output+0x10a/0x2f0
    [   45.102740]  [<c1350216>] ? ip_output+0x76/0x90
    [   45.102750]  [<c134d715>] ? ip_local_out+0x65/0x70
    [   45.102762]  [<c134fa3d>] ? ip_queue_xmit+0x1bd/0x3b0
    [   45.102775]  [<c1362af8>] ? tcp_transmit_skb+0x468/0x7d0
    [   45.102788]  [<c13215af>] ? sk_reset_timer+0xf/0x20
    [   45.102798]  [<c1362446>] ? tcp_event_new_data_sent+0x86/0xc0
    [   45.102809]  [<c1364fc1>] ? tcp_write_xmit+0x1e1/0x9a0
    [   45.102822]  [<c1326925>] ? __alloc_skb+0x55/0x100
    [   45.102838]  [<c102df37>] ? local_bh_enable+0x47/0xa0
    [   45.102849]  [<c1321246>] ? release_sock+0xd6/0x110
    [   45.102859]  [<c13657f7>] ? __tcp_push_pending_frames+0x27/0x80
    [   45.102870]  [<c13584fa>] ? tcp_sendmsg+0x64a/0xac0

    -*- and then system FREEZE -*-


and again, reverting e8616b6ce helps -- X works without a problem.

I've sent this and other info via email:

https://lkml.org/lkml/2011/7/22/93

Comment 7 Kirill Smelkov 2011-08-25 15:40:04 UTC

This was fixed in

commit f3234706a77bd6e1592ae71fb3268e04cb030dba
Author: Keith Packard <keithp@keithp.com>
Date:   Fri Jul 22 10:44:39 2011 -0700

    drm/i915: Initialize RCS ring status page address in intel_render_ring_init_dri
    
    Physically-addressed hardware status pages are initialized early in
    the driver load process by i915_init_phys_hws. For UMS environments,
    the ring structure is not initialized until the X server starts. At
    that point, the entire ring structure is re-initialized with all new
    values. Any values set in the ring structure (including
    ring->status_page.page_addr) will be lost when the ring is
    re-initialized.
    
    This patch moves the initialization of the status_page.page_addr value
    to intel_render_ring_init_dri.
    
    Signed-off-by: Keith Packard <keithp@keithp.com>
    Cc: stable@kernel.org


The fix will be included into v3.1 and is already available in v3.0.3.

Thanks.