Bug 13707

Summary: GEM, Intel E7221, i915 hardware wedged
Product: Drivers Reporter: Jeremy Huddleston (jeremyhu)
Component: Video(DRI - non Intel)Assignee: drivers_video-dri
Status: RESOLVED UNREPRODUCIBLE    
Severity: normal    
Priority: P1    
Hardware: All   
OS: Linux   
URL: https://bugs.freedesktop.org/show_bug.cgi?id=22600
Kernel Version: 2.6.28.x Subsystem:
Regression: No Bisected commit-id:

Description Jeremy Huddleston 2009-07-04 19:15:12 UTC
This has also been reported to mesa's bugzilla (see URL)

I help administer a box for a group I used to belong to, and they're  
having some trouble with recent X.  They've got a dell with Intel  
E7221 integrated graphics.  With recent servers, the console locks up  
as soon as X starts.  The screen blanks, and there is no more possible  
interaction with the server.  Even after killing X, the machine  
remains in this state (I haven't tried video POST or anything like  
that yet to restore functionality, but that's not my main concern).

The 1.4 server with the Intel 2.5.1 drivers works great.  Updating the  
server from 1.4.x to ~1.5.3 or ~1.6.2rc2 causes this failure.

Works: 1.4.x server, Intel 2.5.1 drivers
Fails: 1.5.x or 2.5.x server, any intel drivers (tried 2.5.1 as well  
as through 2.7.x)

I've tried 2.6.26, 2.6.27, and 2.6.28 kernels.  Due to another,  
unrelated bug, I cannot try 2.6.29 at this time.


stdout/err:

This is a pre-release version of the X server from The X.Org Foundation.
It is not supported in any way.
Bugs may be filed in the bugzilla at http://bugs.freedesktop.org/.
Select the "xorg" product for bugs you find in this release.
Before reporting bugs in pre-release versions please check the
latest version in the X.Org Foundation git repository.
See http://wiki.x.org/wiki/GitPage for git access instructions.

X.Org X Server 1.6.1.901 (1.6.2 RC 1)
Release Date: 2009-5-8
X Protocol Version 11, Revision 0
Build Operating System: Linux 2.6.28-hardened-r9 i686
Current Operating System: Linux upe.cs.berkeley.edu 2.6.28-hardened-r9  
#2 SMP Wed Jul 1 00:03:10 PDT 2009 i686
Build Date: 30 June 2009  01:04:39PM

        Before reporting problems, check http://wiki.x.org
        to make sure that you have the latest version.
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: Wed Jul  1 08:01:19 2009
(==) Using config file: "/etc/X11/xorg.conf"
(EE) Failed to load module "type1" (module does not exist, 0)
(EE) Failed to load module "freetype" (module does not exist, 0)
get fences failed: -1
param: 6, val: 0
Setting master
get fences failed: -1
param: 6, val: 0

---

syslog, facility=kern:
2009-07-01T08:01:21-07:00 <kern.info> upe [drm] Initialized drm 1.1.0  
20060810
2009-07-01T08:01:21-07:00 <kern.info> upe pci 0000:00:02.0: PCI INT A - 
 > GSI 16 (level, low) -> IRQ 16
2009-07-01T08:01:21-07:00 <kern.info> upe [drm] Initialized i915 1.6.0  
20080730 on minor 0
2009-07-01T08:01:21-07:00 <kern.err> upe [drm:i915_getparam] *ERROR*  
Unknown parameter 6
2009-07-01T08:01:21-07:00 <kern.err> upe [drm:i915_getparam] *ERROR*  
Unknown parameter 6


---

Xorg.0.log:

This is a pre-release version of the X server from The X.Org Foundation.
It is not supported in any way.
Bugs may be filed in the bugzilla at http://bugs.freedesktop.org/.
Select the "xorg" product for bugs you find in this release.
Before reporting bugs in pre-release versions please check the
latest version in the X.Org Foundation git repository.
See http://wiki.x.org/wiki/GitPage for git access instructions.

X.Org X Server 1.6.1.901 (1.6.2 RC 1)
Release Date: 2009-5-8
X Protocol Version 11, Revision 0
Build Operating System: Linux 2.6.28-hardened-r9 i686
Current Operating System: Linux upe.cs.berkeley.edu 2.6.28-hardened-r9  
#2 SMP Wed Jul 1 00:03:10 PDT 2009 i686
Build Date: 30 June 2009  01:04:39PM

        Before reporting problems, check http://wiki.x.org
        to make sure that you have the latest version.
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: Wed Jul  1 08:01:19 2009
(==) Using config file: "/etc/X11/xorg.conf"
(==) ServerLayout "Main Layout"
(**) |-->Screen "SCREEN" (0)
(**) |   |-->Monitor "Dell D1626HT"
(**) |   |-->Device "INTEL"
(**) |-->Input Device "Mouse1"
(**) |-->Input Device "Keyboard1"
(**) Option "BlankTime" "10"
(**) Option "StandbyTime" "20"
(**) Option "SuspendTime" "30"
(**) Option "OffTime" "60"
(==) Automatically adding devices
(==) Automatically enabling devices
(WW) The directory "/usr/share/fonts/Speedo/" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/OTF" does not exist.
        Entry deleted from font path.
(**) FontPath set to:
        /usr/share/fonts/local/,
        /usr/share/fonts/misc/,
        /usr/share/fonts/75dpi/:unscaled,
        /usr/share/fonts/100dpi/:unscaled,
        /usr/share/fonts/Type1/,
        /usr/share/fonts/TTF/,
        /usr/share/fonts/75dpi/,
        /usr/share/fonts/100dpi/,
        /usr/share/fonts/misc/,
        /usr/share/fonts/TTF/,
        /usr/share/fonts/Type1/,
        /usr/share/fonts/100dpi/,
        /usr/share/fonts/75dpi/,
        built-ins
(==) ModulePath set to "/usr/lib/xorg/modules"
(WW) AllowEmptyInput is on, devices using drivers 'kbd', 'mouse' or  
'vmmouse' will be disabled.
(WW) Disabling Mouse1
(WW) Disabling Keyboard1
(II) Loader magic: 0x1faa0
(II) Module ABI versions:
        X.Org ANSI C Emulation: 0.4
        X.Org Video Driver: 5.0
        X.Org XInput driver : 4.0
        X.Org Server Extension : 2.0
(II) Loader running on linux
(--) using VT number 7

(--) PCI:*(0:0:2:0) 8086:258a:1028:0180 Intel Corporation E7221  
Integrated Graphics Controller rev 4, Mem @ 0xdff80000/524288,  
0xf0000000/134217728, 0xdff40000/262144, I/O @ 0x0000ecd8/8
(II) Open ACPI successful (/var/run/acpid.socket)
(II) System resource ranges:
        [0] -1  0       0xffffffff - 0xffffffff (0x1) MX[B]
        [1] -1  0       0x000f0000 - 0x000fffff (0x10000) MX[B]
        [2] -1  0       0x000c0000 - 0x000effff (0x30000) MX[B]
        [3] -1  0       0x00000000 - 0x0009ffff (0xa0000) MX[B]
        [4] -1  0       0x0000ffff - 0x0000ffff (0x1) IX[B]
        [5] -1  0       0x00000000 - 0x00000000 (0x1) IX[B]
(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) "record" will be loaded by default.
(II) "dri" will be loaded. This was enabled by default and also  
specified in the config file.
(II) "dri2" will be loaded by default.
(II) LoadModule: "dbe"
(II) Loading /usr/lib/xorg/modules/extensions//libdbe.so
(II) Module dbe: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.0.0
        Module class: X.Org Server Extension
        ABI class: X.Org Server Extension, version 2.0
(II) Loading extension DOUBLE-BUFFER
(II) LoadModule: "extmod"
(II) Loading /usr/lib/xorg/modules/extensions//libextmod.so
(II) Module extmod: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.0.0
        Module class: X.Org Server Extension
        ABI class: X.Org Server Extension, version 2.0
(II) Loading extension MIT-SCREEN-SAVER
(II) Loading extension XFree86-VidModeExtension
(II) Loading extension DPMS
(II) Loading extension XVideo
(II) Loading extension XVideo-MotionCompensation
(II) Loading extension X-Resource
(II) LoadModule: "type1"
(WW) Warning, couldn't open module type1
(II) UnloadModule: "type1"
(EE) Failed to load module "type1" (module does not exist, 0)
(II) LoadModule: "freetype"
(WW) Warning, couldn't open module freetype
(II) UnloadModule: "freetype"
(EE) Failed to load module "freetype" (module does not exist, 0)
(II) LoadModule: "dri"
(II) Loading /usr/lib/xorg/modules/extensions//libdri.so
(II) Module dri: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.0.0
        ABI class: X.Org Server Extension, version 2.0
(II) Loading extension XFree86-DRI
(II) LoadModule: "glx"
(II) Loading /usr/lib/xorg/modules/extensions//libglx.so
(II) Module glx: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.0.0
        ABI class: X.Org Server Extension, version 2.0
(==) AIGLX enabled
(II) Loading extension GLX
(II) LoadModule: "record"
(II) Loading /usr/lib/xorg/modules/extensions//librecord.so
(II) Module record: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.13.0
        Module class: X.Org Server Extension
        ABI class: X.Org Server Extension, version 2.0
(II) Loading extension RECORD
(II) LoadModule: "dri2"
(II) Loading /usr/lib/xorg/modules/extensions//libdri2.so
(II) Module dri2: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.1.0
        ABI class: X.Org Server Extension, version 2.0
(II) Loading extension DRI2
(II) LoadModule: "intel"
(II) Loading /usr/lib/xorg/modules/drivers//intel_drv.so
(II) Module intel: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 2.7.1
        Module class: X.Org Video Driver
        ABI class: X.Org Video Driver, version 5.0
(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, IGD_GM, IGD_G, 965G, G35,
        965Q, 946GZ, 965GM, 965GME/GLE, G33, Q35, Q33,
        Mobile IntelĀ® GM45 Express Chipset,
        Intel Integrated Graphics Device, G45/G43, Q45/Q43, G41
(II) Primary Device is: PCI 00@00:02:0
(II) resource ranges after xf86ClaimFixedResources() call:
        [0] -1  0       0xffffffff - 0xffffffff (0x1) MX[B]
        [1] -1  0       0x000f0000 - 0x000fffff (0x10000) MX[B]
        [2] -1  0       0x000c0000 - 0x000effff (0x30000) MX[B]
        [3] -1  0       0x00000000 - 0x0009ffff (0xa0000) MX[B]
        [4] -1  0       0x0000ffff - 0x0000ffff (0x1) IX[B]
        [5] -1  0       0x00000000 - 0x00000000 (0x1) IX[B]
(II) resource ranges after probing:
        [0] -1  0       0xffffffff - 0xffffffff (0x1) MX[B]
        [1] -1  0       0x000f0000 - 0x000fffff (0x10000) MX[B]
        [2] -1  0       0x000c0000 - 0x000effff (0x30000) MX[B]
        [3] -1  0       0x00000000 - 0x0009ffff (0xa0000) MX[B]
        [4] 0   0       0x000a0000 - 0x000affff (0x10000) MS[B]
        [5] 0   0       0x000b0000 - 0x000b7fff (0x8000) MS[B]
        [6] 0   0       0x000b8000 - 0x000bffff (0x8000) MS[B]
        [7] -1  0       0x0000ffff - 0x0000ffff (0x1) IX[B]
        [8] -1  0       0x00000000 - 0x00000000 (0x1) IX[B]
        [9] 0   0       0x000003b0 - 0x000003bb (0xc) IS[B]
        [10] 0  0       0x000003c0 - 0x000003df (0x20) IS[B]
(II) Loading sub module "vgahw"
(II) LoadModule: "vgahw"
(II) Loading /usr/lib/xorg/modules//libvgahw.so
(II) Module vgahw: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 0.1.0
        ABI class: X.Org Video Driver, version 5.0
(II) Loading sub module "ramdac"
(II) LoadModule: "ramdac"
(II) Module "ramdac" already built-in
(**) 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) E7221 (i915)
(--) intel(0): Chipset: "E7221 (i915)"
(--) intel(0): Linear framebuffer at 0xF0000000
(--) intel(0): IO registers at addr 0xDFF80000
(WW) intel(0): libpciaccess reported 0 rom size, guessing 64kB
(==) intel(0): Using EXA for acceleration
(II) intel(0): 1 display pipe available.
(II) Loading sub module "ddc"
(II) LoadModule: "ddc"
(II) Module "ddc" already built-in
(II) Loading sub module "i2c"
(II) LoadModule: "i2c"
(II) Module "i2c" already built-in
(II) intel(0): Output VGA using monitor section Dell D1626HT
(II) intel(0): Resizable framebuffer: not available (1 3)
(II) intel(0): I2C bus "CRTDDC_A" initialized.
(II) intel(0): I2C bus "CRTDDC_A" removed.
(II) intel(0): I2C bus "CRTDDC_A" initialized.
(II) intel(0): I2C device "CRTDDC_A:E-EDID segment register"  
registered at address 0x60.
(II) intel(0): I2C device "CRTDDC_A:ddc2" registered at address 0xA0.
(II) intel(0): I2C device "CRTDDC_A:ddc2" removed.
(II) intel(0): I2C device "CRTDDC_A:E-EDID segment register" removed.
(II) intel(0): I2C bus "CRTDDC_A" removed.
(II) intel(0): EDID vendor "DEL", prod id 20827
(II) intel(0): Using EDID range info for horizontal sync
(II) intel(0): Using EDID range info for vertical refresh
(II) intel(0): Printing DDC gathered Modelines:
(II) intel(0): Modeline "720x350"x0.0   28.32  720 738 846 900  350  
388 390 449 +hsync -vsync (31.5 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 "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   25.18  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.75  1024 1040 1136 1312   
768 769 772 800 +hsync +vsync (60.0 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 "800x600"x0.0   49.50  800 816 896 1056  600  
601 604 625 +hsync +vsync (46.9 kHz)
(II) intel(0): Modeline "640x480"x0.0   36.00  640 696 752 832  480  
481 484 509 -hsync -vsync (43.3 kHz)
(II) intel(0): Modeline "800x600"x0.0   56.25  800 832 896 1048  600  
601 604 631 +hsync +vsync (53.7 kHz)
(II) intel(0): Modeline "1024x768"x0.0   94.50  1024 1072 1168 1376   
768 769 772 808 +hsync +vsync (68.7 kHz)
(II) intel(0): Modeline "1280x1024"x0.0  157.50  1280 1344 1504 1728   
1024 1025 1028 1072 +hsync +vsync (91.1 kHz)
(II) intel(0): Modeline "1600x1200"x0.0  202.50  1600 1664 1856 2160   
1200 1201 1204 1250 +hsync +vsync (93.8 kHz)
(II) intel(0): Modeline "1600x1200"x0.0  229.50  1600 1664 1856 2160   
1200 1201 1204 1250 +hsync +vsync (106.2 kHz)
(II) intel(0): EDID vendor "DEL", prod id 20827
(II) intel(0): Output VGA connected
(II) intel(0): Using user preference for initial modes
(II) intel(0): Output VGA using initial mode 1600x1200
(II) intel(0): detected 128 kB GTT.
(II) intel(0): detected 8060 kB stolen memory.
(==) intel(0): video overlay key set to 0x83e
(==) intel(0): Using gamma correction (1.0, 1.0, 1.0)
(**) intel(0): Display dimensions: (380, 290) mm
(**) intel(0): DPI set to (106, 140)
(II) Loading sub module "fb"
(II) LoadModule: "fb"
(II) Loading /usr/lib/xorg/modules//libfb.so
(II) Module fb: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 1.0.0
        ABI class: X.Org ANSI C Emulation, version 0.4
(II) Loading sub module "exa"
(II) LoadModule: "exa"
(II) Loading /usr/lib/xorg/modules//libexa.so
(II) Module exa: vendor="X.Org Foundation"
        compiled for 1.6.1.901, module version = 2.4.0
        ABI class: X.Org Video Driver, version 5.0
(II) intel(0): Comparing regs from server start up to After PreInit
(WW) intel(0): Register 0x70024 (PIPEASTAT) changed from 0x00000a03 to  
0x80000a03
(WW) intel(0): PIPEASTAT before: status: GMBUS_INT_STATUS  
VSYNC_INT_STATUS VBLANK_INT_STATUS OREG_UPDATE_STATUS
(WW) intel(0): PIPEASTAT after: status: FIFO_UNDERRUN GMBUS_INT_STATUS  
VSYNC_INT_STATUS VBLANK_INT_STATUS OREG_UPDATE_STATUS
(WW) intel(0): Register 0x71180 (DSPBCNTR) changed from 0x01000000 to  
0x00000000
(WW) intel(0): DSPBCNTR before: disabled, pipe B
(WW) intel(0): DSPBCNTR after: disabled, pipe A
(II) do I need RAC?  No, I don't.
(II) resource ranges after preInit:
        [0] -1  0       0xffffffff - 0xffffffff (0x1) MX[B]
        [1] -1  0       0x000f0000 - 0x000fffff (0x10000) MX[B]
        [2] -1  0       0x000c0000 - 0x000effff (0x30000) MX[B]
        [3] -1  0       0x00000000 - 0x0009ffff (0xa0000) MX[B]
        [4] 0   0       0x000a0000 - 0x000affff (0x10000) MS[B](OprD)
        [5] 0   0       0x000b0000 - 0x000b7fff (0x8000) MS[B](OprD)
        [6] 0   0       0x000b8000 - 0x000bffff (0x8000) MS[B](OprD)
        [7] -1  0       0x0000ffff - 0x0000ffff (0x1) IX[B]
        [8] -1  0       0x00000000 - 0x00000000 (0x1) IX[B]
        [9] 0   0       0x000003b0 - 0x000003bb (0xc) IS[B](OprU)
        [10] 0  0       0x000003c0 - 0x000003df (0x20) IS[B](OprU)
(II) intel(0): Kernel reported 238592 total, 1 used
(II) intel(0): I830CheckAvailableMemory: 954364 kB available
(WW) intel(0): DRI2 requires UXA
drmOpenDevice: node name is /dev/dri/card0
drmOpenDevice: open result is -1, (No such device or address)
drmOpenDevice: open result is -1, (No such device or address)
drmOpenDevice: Open failed
drmOpenDevice: node name is /dev/dri/card0
drmOpenDevice: open result is -1, (No such device or address)
drmOpenDevice: open result is -1, (No such device or address)
drmOpenDevice: Open failed
drmOpenByBusid: Searching for BusID pci:0000:00:02.0
drmOpenDevice: node name is /dev/dri/card0
drmOpenDevice: open result is 13, (OK)
drmOpenByBusid: drmOpenMinor returns 13
drmOpenByBusid: drmGetBusid reports pci:0000:00:02.0
(II) [drm] loaded kernel module for "i915" driver.
(II) [drm] DRM interface version 1.3
(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 disabled
(**) intel(0): Tiling enabled
(==) intel(0): VideoRam: 131072 KB
(II) intel(0): Attempting memory allocation with tiled buffers.
(II) intel(0): Tiled allocation successful.
(II) intel(0): [drm] Registers = 0xdff80000
(II) intel(0): [dri] visual configs initialized
(II) intel(0): vgaHWGetIOBase: hwp->IOBase is 0x03d0, hwp->PIOOffset  
is 0x0000
(II) EXA(0): Offscreen pixmap area of 19660800 bytes
(II) EXA(0): Driver registered support for the following operations:
(II)         Solid
(II)         Copy
(II)         Composite (RENDER acceleration)
(==) intel(0): Backing store disabled
(==) intel(0): Silken mouse enabled
(II) intel(0): Initializing HW Cursor
(II) intel(0): [DRI] installation complete
(WW) intel(0): drmDropMaster failed: Unknown error 4294967295
(II) intel(0): xf86BindGARTMemory: bind key 0 at 0x06d35000 (pgoffset  
27957)
(II) intel(0): Fixed memory allocation layout:
(II) intel(0): 0x00000000-0x00004fff: HW cursors (20 kB,  
0x000000003f800000 physical
)
(II) intel(0): 0x00005000-0x00005fff: overlay registers (4 kB,  
0x000000003f805000 physical
)
(II) intel(0): 0x007df000:            end of stolen memory
(II) intel(0): 0x007df000-0x06d34fff: DRI memory manager (103768 kB)
(II) intel(0): 0x06d35000-0x07ff4fff: exa offscreen (19200 kB)
(II) intel(0): 0x08000000:            end of aperture
(II) intel(0): BO memory allocation layout:
(II) intel(0): 0x007df000:            start of memory manager
(II) intel(0): 0x00800000-0x00ffffff: depth buffer (8192 kB) X tiled
(II) intel(0): 0x01000000-0x017fffff: back buffer (8192 kB) X tiled
(II) intel(0): 0x01800000-0x01ffffff: front buffer (8192 kB) X tiled
(II) intel(0): 0x06d35000:            end of memory manager
(II) intel(0): [drm] mapped front buffer at 0xf1800000, handle =  
0xf1800000
(II) intel(0): [drm] mapped back buffer at 0xf1000000, handle =  
0xf1000000
(II) intel(0): [drm] mapped depth buffer at 0xf0800000, handle =  
0xf0800000
(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):   Output VGA is connected to pipe A
(II) intel(0): RandR 1.2 enabled, ignore the following RandR disabled  
message.
(**) Option "dpms"
(**) intel(0): DPMS enabled
(==) intel(0): Intel XvMC decoder disabled
(II) intel(0): Set up textured video
(II) intel(0): Set up overlay video
(II) intel(0): direct rendering: XF86DRI Enabled
(--) RandR disabled
(II) Initializing built-in extension Generic Event Extension
(II) Initializing built-in extension SHAPE
(II) Initializing built-in extension MIT-SHM
(II) Initializing built-in extension XInputExtension
(II) Initializing built-in extension XTEST
(II) Initializing built-in extension BIG-REQUESTS
(II) Initializing built-in extension SYNC
(II) Initializing built-in extension XKEYBOARD
(II) Initializing built-in extension XC-MISC
(II) Initializing built-in extension XINERAMA
(II) Initializing built-in extension XFIXES
(II) Initializing built-in extension RENDER
(II) Initializing built-in extension RANDR
(II) Initializing built-in extension COMPOSITE
(II) Initializing built-in extension DAMAGE
(II) AIGLX: Screen 0 is not DRI2 capable
drmOpenDevice: node name is /dev/dri/card0
drmOpenDevice: open result is 14, (OK)
drmOpenByBusid: Searching for BusID pci:0000:00:02.0
drmOpenDevice: node name is /dev/dri/card0
drmOpenDevice: open result is 14, (OK)
drmOpenByBusid: drmOpenMinor returns 14
drmOpenByBusid: drmGetBusid reports pci:0000:00:02.0
(II) AIGLX: enabled GLX_SGI_make_current_read
(II) AIGLX: enabled GLX_MESA_copy_sub_buffer
(II) AIGLX: enabled GLX_SGI_swap_control and GLX_MESA_swap_control
(II) AIGLX: enabled GLX_texture_from_pixmap with driver support
(II) AIGLX: Loaded and initialized /usr/lib/dri/i915_dri.so
(II) GLX: Initialized DRI GL provider for screen 0
(II) intel(0): Setting screen physical size to 388 x 291

---

Then when I kill the X server, I see...

stdout/err:
^CDropping master
error setting MTRR (base = 0xf0000000, size = 0x08000000, type = 1)  
Invalid argument (22)

Xorg.0.log:
(II) intel(0): xf86UnbindGARTMemory: unbind key 0
(WW) intel(0): drmDropMaster failed: Unknown error 4294967295
(II) intel(0): [drm] removed 1 reserved context for kernel
(II) intel(0): [drm] unmapping 8192 bytes of SAREA 0xf8040000 at  
0x52394000
(II) intel(0): [drm] Closed DRM master.

---

Starting X last night while I was there caused got a little more data  
(from syslog):

INFO: task events/0:9 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this  
message.
events/0      D f7077f34     0     9      2
<unusable call trace>
[drm:i915_gem_idle] *ERROR* hardware wedged

The kernel didn't have debug symbols, so the trace was not usable.  I  
rebuilt their kernel last night with debug symbols, so hopefully I can  
get a good trace if I have time to visit them today.
Comment 1 Jeremy Huddleston 2009-07-04 19:17:27 UTC
I'm not getting the stack traces with the rebuilt kernel... I'm just seeing:

[drm] Initialized drm 1.1.0 20060810
pci 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
pci 0000:00:02.0: setting latency timer to 64
[drm] Initialized i915 1.6.0 20080730 on minor 0
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:i915_getparam] *ERROR* Unknown parameter 6
mtrr: no MTRR for f0000000,8000000 found
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:i915_getparam] *ERROR* Unknown parameter 6
mtrr: no MTRR for f0000000,8000000 found
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:i915_gem_idle] *ERROR* hardware wedged
Comment 2 Jeremy Huddleston 2009-07-04 19:31:51 UTC
With debugging on, there is a little more info:


[drm] Initialized drm 1.1.0 20060810
[drm:drm_init] 
[drm:drm_get_dev] 
pci 0000:00:02.0: setting latency timer to 64
[drm:i915_init_phys_hws] Enabled hardware status page
[drm:intel_opregion_init] graphic opregion physical addr: 0x0
[drm:intel_opregion_init] ACPI OpRegion not supported!
[drm:drm_get_minor] 
[drm:drm_get_minor] new minor assigned 0
[drm] Initialized i915 1.6.0 20080730 on minor 0
[drm:drm_stub_open] 
[drm:drm_open_helper] pid = 1286, minor = 0
[drm:i915_driver_open] 
[drm:drm_addmap_core] offset = 0x00000000, size = 0x00002000, type = 2
[drm:drm_addmap_core] 8192 13 f8034000
[drm:drm_setup] 
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_release] open_count = 1
[drm:drm_release] pid = 1286, device = 0xe200, open_count = 1
[drm:drm_lastclose] 
[drm:drm_lastclose] driver lastclose completed
[drm:drm_lastclose] lastclose completed
[drm:drm_stub_open] 
[drm:drm_open_helper] pid = 1286, minor = 0
[drm:i915_driver_open] 
[drm:drm_addmap_core] offset = 0x00000000, size = 0x00002000, type = 2
[drm:drm_addmap_core] 8192 13 f8038000
[drm:drm_setup] 
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_release] open_count = 1
[drm:drm_release] pid = 1286, device = 0xe200, open_count = 1
[drm:drm_lastclose] 
[drm:drm_lastclose] driver lastclose completed
[drm:drm_lastclose] lastclose completed
[drm:drm_stub_open] 
[drm:drm_open_helper] pid = 1286, minor = 0
[drm:i915_driver_open] 
[drm:drm_addmap_core] offset = 0x00000000, size = 0x00002000, type = 2
[drm:drm_addmap_core] 8192 13 f803c000
[drm:drm_setup] 
[drm:drm_ioctl] pid=1286, cmd=0xc0106407, nr=0x07, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0106407, nr=0x07, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0186415, nr=0x15, dev 0xe200, auth=1
[drm:drm_addmap_core] offset = 0x00000000, size = 0x00002000, type = 2
[drm:drm_mmap_locked] start = 0x53f1a000, end = 0x53f1c000, page offset = 0xf803c
[drm:drm_vm_open_locked] 0x53f1a000,0x00002000
[drm:drm_do_vm_shm_fault] shm_fault 0x0
[drm:drm_do_vm_shm_fault] shm_fault 0x1000
[drm:drm_ioctl] pid=1286, cmd=0xc0086426, nr=0x26, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086426, nr=0x26, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086420, nr=0x20, dev 0xe200, auth=1
[drm:drm_addctx] 1
[drm:drm_ioctl] pid=1286, cmd=0x40086422, nr=0x22, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x4008642a, nr=0x2a, dev 0xe200, auth=1
[drm:drm_lock] 1 (pid 1286) requests lock (0x00000000), flags = 0x00000000
[drm:drm_lock] 1 has lock
[drm:drm_fasync] fd = 13, device = 0xe200
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40106453, nr=0x53, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x80106463, nr=0x63, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:drm_ioctl] ret = ffffffea
[drm:drm_ioctl] pid=1286, cmd=0xc010645b, nr=0x5b, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0106461, nr=0x61, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc010645b, nr=0x5b, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0106461, nr=0x61, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc010645b, nr=0x5b, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0106461, nr=0x61, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0186415, nr=0x15, dev 0xe200, auth=1
[drm:drm_addmap_core] offset = 0xdff80000, size = 0x00080000, type = 1
[drm:drm_ioctl] pid=1286, cmd=0x40446440, nr=0x40, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40086447, nr=0x47, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x641e, nr=0x1e, dev 0xe200, auth=1
[drm:drm_ioctl] no function
[drm:drm_ioctl] ret = ffffffea
[drm:drm_ioctl] pid=1286, cmd=0x6459, nr=0x59, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:drm_irq_install] irq=16
[drm:drm_ioctl] pid=1286, cmd=0xc0186455, nr=0x55, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:drm_ioctl] pid=1286, cmd=0xc0186455, nr=0x55, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:drm_ioctl] pid=1286, cmd=0xc0186455, nr=0x55, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:drm_ioctl] pid=1286, cmd=0xc010645b, nr=0x5b, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x400c645f, nr=0x5f, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x4004644d, nr=0x4d, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x4004644d, nr=0x4d, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40086408, nr=0x08, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x4004644d, nr=0x4d, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0186415, nr=0x15, dev 0xe200, auth=1
[drm:drm_addmap_core] offset = 0xf1800000, size = 0x00640000, type = 3
[drm:drm_addmap_core] AGP offset = 0xf1800000, size = 0x00640000
[drm:drm_ioctl] pid=1286, cmd=0xc0186415, nr=0x15, dev 0xe200, auth=1
[drm:drm_addmap_core] offset = 0xf1000000, size = 0x00800000, type = 3
[drm:drm_addmap_core] AGP offset = 0xf1000000, size = 0x00800000
[drm:drm_ioctl] pid=1286, cmd=0xc0186415, nr=0x15, dev 0xe200, auth=1
[drm:drm_addmap_core] offset = 0xf0800000, size = 0x00800000, type = 3
[drm:drm_addmap_core] AGP offset = 0xf0800000, size = 0x00800000
[drm:drm_ioctl] pid=1286, cmd=0xc008640a, nr=0x0a, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc008640a, nr=0x0a, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc008640a, nr=0x0a, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x4004644d, nr=0x4d, dev 0xe200, auth=1
[drm:drm_stub_open] 
[drm:drm_open_helper] pid = 1286, minor = 0
[drm:i915_driver_open] 
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_release] open_count = 2
[drm:drm_release] pid = 1286, device = 0xe200, open_count = 2
[drm:drm_stub_open] 
[drm:drm_open_helper] pid = 1286, minor = 0
[drm:i915_driver_open] 
[drm:drm_ioctl] pid=1286, cmd=0xc0106407, nr=0x07, dev 0xe200, auth=1
[drm:drm_ioctl] ret = fffffff3
[drm:drm_ioctl] pid=1286, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x80046402, nr=0x02, dev 0xe200, auth=1
[drm:drm_add_magic] 1
[drm:drm_getmagic] 1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40046411, nr=0x11, dev 0xe200, auth=1
[drm:drm_authmagic] 1
[drm:drm_remove_magic] 1
[drm:drm_mmap_locked] start = 0x4b553000, end = 0x4bb93000, page offset = 0xf1800
[drm:drm_mmap_locked]    Type = 3; start = 0x4b553000, end = 0x4bb93000, offset = 0xf1800000
[drm:drm_vm_open_locked] 0x4b553000,0x00640000
[drm:drm_mmap_locked] start = 0x54009000, end = 0x5400b000, page offset = 0xf803c
[drm:drm_vm_open_locked] 0x54009000,0x00002000
[drm:drm_do_vm_shm_fault] shm_fault 0x1000
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x80106463, nr=0x63, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0086446, nr=0x46, dev 0xe200, auth=1
[drm:i915_getparam] *ERROR* Unknown parameter 6
[drm:drm_ioctl] ret = ffffffea
[drm:drm_ioctl] pid=1286, cmd=0x400c645f, nr=0x5f, dev 0xe200, auth=1
[drm:drm_vm_open_locked] 0x4b553000,0x00640000
[drm:drm_vm_open_locked] 0x53f1a000,0x00002000
[drm:drm_vm_open_locked] 0x54009000,0x00002000
[drm:drm_vm_close] 0x4b553000,0x00640000
[drm:drm_vm_shm_close] 0x53f1a000,0x00002000
[drm:drm_vm_shm_close] 0x54009000,0x00002000
[drm:drm_ioctl] pid=1286, cmd=0x4004644d, nr=0x4d, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40046460, nr=0x60, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40286454, nr=0x54, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:i915_add_request] 1
[drm:i915_add_request] 2
[drm:drm_ioctl] pid=1286, cmd=0xc0086457, nr=0x57, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc010645b, nr=0x5b, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x400c645f, nr=0x5f, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x6458, nr=0x58, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x6458, nr=0x58, dev 0xe200, auth=1

## This above line repeats about 600 times

[drm:drm_ioctl] pid=1286, cmd=0x40046460, nr=0x60, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x40286454, nr=0x54, dev 0xe200, auth=1
[drm:drm_agp_bind_pages] 
[drm:i915_add_request] 3
[drm:i915_add_request] 4
[drm:drm_ioctl] pid=1286, cmd=0xc0086457, nr=0x57, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0x400c645f, nr=0x5f, dev 0xe200, auth=1
[drm:drm_ioctl] pid=1286, cmd=0xc0046444, nr=0x44, dev 0xe200, auth=1
[drm:i915_emit_irq] 
[drm:drm_ioctl] pid=1286, cmd=0x40046445, nr=0x45, dev 0xe200, auth=1
[drm:i915_wait_irq] irq_nr=1 breadcrumb=0
[drm:drm_ioctl] ret = fffffffc
[drm:drm_ioctl] pid=1286, cmd=0x40046445, nr=0x45, dev 0xe200, auth=1
[drm:i915_wait_irq] irq_nr=1 breadcrumb=0
[drm:drm_ioctl] ret = fffffffc
[drm:drm_ioctl] pid=1286, cmd=0x40046445, nr=0x45, dev 0xe200, auth=1
[drm:i915_wait_irq] irq_nr=1 breadcrumb=0
[drm:drm_ioctl] ret = fffffffc
[drm:drm_ioctl] pid=1286, cmd=0x40046445, nr=0x45, dev 0xe200, auth=1
[drm:i915_wait_irq] irq_nr=1 breadcrumb=0
[drm:drm_ioctl] ret = fffffffc

# This repeats until I kill the server.


# KILL THE SERVER (pid=1286) HERE


[drm:drm_vm_close] 0x4b553000,0x00640000
[drm:drm_vm_shm_close] 0x53f1a000,0x00002000
[drm:drm_vm_shm_close] 0x54009000,0x00002000
[drm:drm_fasync] fd = -1, device = 0xe200
[drm:drm_release] open_count = 2
[drm:drm_release] pid = 1286, device = 0xe200, open_count = 2
[drm:drm_release] File f5d5be80 released, freeing lock for context 1
[drm:drm_release] open_count = 1
[drm:drm_release] pid = 1286, device = 0xe200, open_count = 1
[drm:drm_lastclose] 
[drm:i915_add_request] 5
[drm:drm_irq_uninstall] irq=16
[drm:drm_lastclose] driver lastclose completed
[drm:drm_lastclose] lastclose completed
Comment 3 Jeremy Huddleston 2009-07-07 04:43:17 UTC
I can't seem to reproduce the wedge after rebuilding a few packages.  I got a newer gcc, rebuilt glibc, and rebuilt the kernel with debugging symbols and a frame pointer... so it may have been a buggy gcc frame-pointer issue (?) ... anyways, I'll reopen if I can narrow it down more.