Bug 94092

Summary: X crash: intel(0): Failed to submit rendering commands (Cannot allocate memory), disabling acceleration.
Product: DRI Reporter: David Biesack <David.Biesack>
Component: DRM/IntelAssignee: Intel GFX Bugs mailing list <intel-gfx-bugs>
Status: CLOSED DUPLICATE QA Contact: Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity: normal    
Priority: medium CC: intel-gfx-bugs
Version: XOrg git   
Hardware: Other   
OS: All   
Whiteboard:
i915 platform: i915 features:
Attachments:
Description Flags
vbiod.dump
none
/var/log/Xorg.0.log.old leading up to latest X crash
none
xrestop.log
none
xrestop -b -m 1
none
/sys/kernel/debug/dri/0/i915_gem_objects none

Description David Biesack 2016-02-11 14:58:31 UTC 
Created attachment 121676 [details]
vbiod.dump

X is crashing on me intermittently, but multiple times a week.
/var/log/Xorg.0.log.old contains:

[ 81726.376] (EE) intel(0): Failed to submit rendering commands (Cannot allocate memory), disabling acceleration.
[ 81726.387] (EE) intel(0): When reporting this, please include /sys/class/drm/card0/error and the full dmesg.
[ 81727.568] (EE) 
[ 81727.568] (EE) Backtrace:
[ 81727.606] (EE) 0: /usr/bin/X (xorg_backtrace+0x4e) [0x55d4c019468e]
[ 81727.606] (EE) 1: /usr/bin/X (0x55d4bffe0000+0x1b89f9) [0x55d4c01989f9]
[ 81727.606] (EE) 2: /lib/x86_64-linux-gnu/libc.so.6 (0x7f2c6ceaa000+0x352f0) [0x7f2c6cedf2f0]
[ 81727.606] (EE) 3: /usr/lib/xorg/modules/drivers/intel_drv.so (0x7f2c69281000+0x1046cb) [0x7f2c693856cb]
[ 81727.606] (EE) 4: /usr/lib/xorg/modules/drivers/intel_drv.so (0x7f2c69281000+0x10749b) [0x7f2c6938849b]
[ 81727.606] (EE) 5: /usr/bin/X (DRI2SwapBuffers+0x1c8) [0x55d4c0166d58]
[ 81727.606] (EE) 6: /usr/bin/X (0x55d4bffe0000+0x1886dc) [0x55d4c01686dc]
[ 81727.606] (EE) 7: /usr/bin/X (0x55d4bffe0000+0x5818f) [0x55d4c003818f]
[ 81727.606] (EE) 8: /usr/bin/X (0x55d4bffe0000+0x5c34b) [0x55d4c003c34b]
[ 81727.606] (EE) 9: /lib/x86_64-linux-gnu/libc.so.6 (__libc_start_main+0xf0) [0x7f2c6cecaa40]
[ 81727.606] (EE) 10: /usr/bin/X (_start+0x29) [0x55d4c00266c9]
[ 81727.606] (EE) 
[ 81727.606] (EE) Segmentation fault at address 0x10
[ 81727.607] (EE) 
Fatal server error:
[ 81727.607] (EE) Caught signal 11 (Segmentation fault). Server aborting
[ 81727.607] (EE) 
[ 81727.607] (EE) 
Please consult the The X.Org Foundation support 
	 at http://wiki.x.org
 for help. 
[ 81727.607] (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information.
[ 81727.607] (EE) 
[ 81727.643] (II) AIGLX: Suspending AIGLX clients for VT switch
[ 81727.671] (EE) Server terminated with error (1). Closing log file.

/sys/class/drm/card0/error contains only:
no error state collected

I've not enabled  drm.debug=0xe  but will try; I'm not sure how to actually do that.

System is XUbuntu 15.10 installed on Jan 15, 2016.
I've installed https://download.01.org/gfx/ubuntu/15.10/main/pool/main/i/intel-linux-graphics-installer/intel-linux-graphics-installer_1.2.1-0intel2_amd64.deb

and run intel-linux-graphics-installer successfully


	Added:
	    intel-gpu-tools:amd64 (1.12-1)
	    libunwind8:amd64 (1.1-4)
	    libva-egl1:amd64 (1.6.1-1)
	    libva-glx1:amd64 (1.6.1-1)
	    libva-tpi1:amd64 (1.6.1-1)
	    libva-wayland1:amd64 (1.6.1-1)
	    vainfo:amd64 (1.6.1-1)

	Upgraded:
	    i965-va-driver:amd64 (from 1.6.0-1 to 1.6.1-1)
	    libva-drm1:amd64 (from 1.6.0-1 to 1.6.1-1)
	    libva-x11-1:amd64 (from 1.6.0-1 to 1.6.1-1)
	    libva1:amd64 (from 1.6.0-1 to 1.6.1-1)
	    va-driver-all:amd64 (from 1.6.0-1 to 1.6.1-1)

other system info
$ uname -m
x86_64
$ uname -r
4.2.0-23-generic

$ xrandr --verbose
Screen 0: minimum 8 x 8, current 3200 x 1200, maximum 32767 x 32767
DP1 disconnected (normal left inverted right x axis y axis)
	Identifier: 0x43
	Timestamp:  82053148
	Subpixel:   unknown
	Clones:    
	CRTCs:      0 1 2
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 
	Broadcast RGB: Automatic 
		supported: Automatic, Full, Limited 16:235
	audio: auto 
		supported: force-dvi, off, auto, on
DP2 disconnected (normal left inverted right x axis y axis)
	Identifier: 0x44
	Timestamp:  82053148
	Subpixel:   unknown
	Clones:    
	CRTCs:      0 1 2
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 
	Broadcast RGB: Automatic 
		supported: Automatic, Full, Limited 16:235
	audio: auto 
		supported: force-dvi, off, auto, on
HDMI1 connected 1600x1200+0+0 (0x4a) normal (normal left inverted right x axis y axis) 367mm x 275mm
	Identifier: 0x45
	Timestamp:  82053148
	Subpixel:   unknown
	Gamma:      1.0:1.0:1.0
	Brightness: 1.0
	Clones:     VGA1
	CRTC:       0
	CRTCs:      0 1 2
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 
	EDID: 
		00ffffffffffff0010ac21a04c374d32
		2510010380291f78eeee91a3544c9926
		0f5054a54b008180a940714f01010101
		010101010101483f403062b0324040c0
		13006f131100001e000000ff00433935
		3336363944324d374c0a000000fc0044
		454c4c203230303746500a20000000fd
		00384c1e5311000a202020202020009e
	aspect ratio: Automatic 
		supported: Automatic, 4:3, 16:9
	Broadcast RGB: Automatic 
		supported: Automatic, Full, Limited 16:235
	audio: auto 
		supported: force-dvi, off, auto, on
  1600x1200 (0x4a) 162.000MHz +HSync +VSync *current +preferred
        h: width  1600 start 1664 end 1856 total 2160 skew    0 clock  75.00KHz
        v: height 1200 start 1201 end 1204 total 1250           clock  60.00Hz
  1280x1024 (0xe5) 135.000MHz +HSync +VSync
        h: width  1280 start 1296 end 1440 total 1688 skew    0 clock  79.98KHz
        v: height 1024 start 1025 end 1028 total 1066           clock  75.02Hz
  1280x1024 (0xe6) 108.000MHz +HSync +VSync
        h: width  1280 start 1328 end 1440 total 1688 skew    0 clock  63.98KHz
        v: height 1024 start 1025 end 1028 total 1066           clock  60.02Hz
  1152x864 (0xe7) 108.000MHz +HSync +VSync
        h: width  1152 start 1216 end 1344 total 1600 skew    0 clock  67.50KHz
        v: height  864 start  865 end  868 total  900           clock  75.00Hz
  1024x768 (0xe8) 78.800MHz +HSync +VSync
        h: width  1024 start 1040 end 1136 total 1312 skew    0 clock  60.06KHz
        v: height  768 start  769 end  772 total  800           clock  75.08Hz
  1024x768 (0xe9) 65.000MHz -HSync -VSync
        h: width  1024 start 1048 end 1184 total 1344 skew    0 clock  48.36KHz
        v: height  768 start  771 end  777 total  806           clock  60.00Hz
  800x600 (0xea) 49.500MHz +HSync +VSync
        h: width   800 start  816 end  896 total 1056 skew    0 clock  46.88KHz
        v: height  600 start  601 end  604 total  625           clock  75.00Hz
  800x600 (0xeb) 40.000MHz +HSync +VSync
        h: width   800 start  840 end  968 total 1056 skew    0 clock  37.88KHz
        v: height  600 start  601 end  605 total  628           clock  60.32Hz
  640x480 (0xec) 31.500MHz -HSync -VSync
        h: width   640 start  656 end  720 total  840 skew    0 clock  37.50KHz
        v: height  480 start  481 end  484 total  500           clock  75.00Hz
  640x480 (0xed) 25.200MHz -HSync -VSync
        h: width   640 start  656 end  752 total  800 skew    0 clock  31.50KHz
        v: height  480 start  490 end  492 total  525           clock  60.00Hz
  720x400 (0xee) 28.320MHz -HSync +VSync
        h: width   720 start  738 end  846 total  900 skew    0 clock  31.47KHz
        v: height  400 start  412 end  414 total  449           clock  70.08Hz
HDMI2 connected 1600x1200+1600+0 (0x4a) normal (normal left inverted right x axis y axis) 367mm x 275mm
	Identifier: 0x46
	Timestamp:  82053148
	Subpixel:   unknown
	Gamma:      1.0:1.0:1.0
	Brightness: 1.0
	Clones:     VGA1
	CRTC:       1
	CRTCs:      0 1 2
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 
	EDID: 
		00ffffffffffff0010ac21a053434c31
		1211010380291f78eeee91a3544c9926
		0f5054a54b008180a940714f01010101
		010101010101483f403062b0324040c0
		13006f131100001e000000ff00433935
		3336373533314c43530a000000fc0044
		454c4c203230303746500a20000000fd
		00384c1e5311000a20202020202000a2
	aspect ratio: Automatic 
		supported: Automatic, 4:3, 16:9
	Broadcast RGB: Automatic 
		supported: Automatic, Full, Limited 16:235
	audio: auto 
		supported: force-dvi, off, auto, on
  1600x1200 (0x4a) 162.000MHz +HSync +VSync *current +preferred
        h: width  1600 start 1664 end 1856 total 2160 skew    0 clock  75.00KHz
        v: height 1200 start 1201 end 1204 total 1250           clock  60.00Hz
  1280x1024 (0xe5) 135.000MHz +HSync +VSync
        h: width  1280 start 1296 end 1440 total 1688 skew    0 clock  79.98KHz
        v: height 1024 start 1025 end 1028 total 1066           clock  75.02Hz
  1280x1024 (0xe6) 108.000MHz +HSync +VSync
        h: width  1280 start 1328 end 1440 total 1688 skew    0 clock  63.98KHz
        v: height 1024 start 1025 end 1028 total 1066           clock  60.02Hz
  1152x864 (0xe7) 108.000MHz +HSync +VSync
        h: width  1152 start 1216 end 1344 total 1600 skew    0 clock  67.50KHz
        v: height  864 start  865 end  868 total  900           clock  75.00Hz
  1024x768 (0xe8) 78.800MHz +HSync +VSync
        h: width  1024 start 1040 end 1136 total 1312 skew    0 clock  60.06KHz
        v: height  768 start  769 end  772 total  800           clock  75.08Hz
  1024x768 (0xe9) 65.000MHz -HSync -VSync
        h: width  1024 start 1048 end 1184 total 1344 skew    0 clock  48.36KHz
        v: height  768 start  771 end  777 total  806           clock  60.00Hz
  800x600 (0xea) 49.500MHz +HSync +VSync
        h: width   800 start  816 end  896 total 1056 skew    0 clock  46.88KHz
        v: height  600 start  601 end  604 total  625           clock  75.00Hz
  800x600 (0xeb) 40.000MHz +HSync +VSync
        h: width   800 start  840 end  968 total 1056 skew    0 clock  37.88KHz
        v: height  600 start  601 end  605 total  628           clock  60.32Hz
  640x480 (0xec) 31.500MHz -HSync -VSync
        h: width   640 start  656 end  720 total  840 skew    0 clock  37.50KHz
        v: height  480 start  481 end  484 total  500           clock  75.00Hz
  640x480 (0xed) 25.200MHz -HSync -VSync
        h: width   640 start  656 end  752 total  800 skew    0 clock  31.50KHz
        v: height  480 start  490 end  492 total  525           clock  60.00Hz
  720x400 (0xee) 28.320MHz -HSync +VSync
        h: width   720 start  738 end  846 total  900 skew    0 clock  31.47KHz
        v: height  400 start  412 end  414 total  449           clock  70.08Hz
VGA1 disconnected (normal left inverted right x axis y axis)
	Identifier: 0x47
	Timestamp:  82053148
	Subpixel:   unknown
	Clones:     HDMI1 HDMI2
	CRTCs:      0 1 2
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 
VIRTUAL1 disconnected (normal left inverted right x axis y axis)
	Identifier: 0x48
	Timestamp:  82053148
	Subpixel:   no subpixels
	Clones:    
	CRTCs:      3
	Transform:  1.000000 0.000000 0.000000
	            0.000000 1.000000 0.000000
	            0.000000 0.000000 1.000000
	           filter: 



I could not include intel_reg_dumper -- the link https://01.org/linuxgraphics/documentation/using-intel-reg-dumper-0
(from https://01.org/linuxgraphics/documentation/how-report-bugs) is broken

vbios.dump is attached
Comment 1 Chris Wilson 2016-02-11 21:32:05 UTC 
System ran out of memory, and malloc failed - obviously one is unguarded. More interesting would be the full Xorg.0.log, cat /sys/kernel/debug/dri/0/i915_gem_objects and xrestop after a day or so of uptime.
Comment 2 David Biesack 2016-02-12 14:16:01 UTC 
Created attachment 121709 [details]
/var/log/Xorg.0.log.old leading up to latest X crash
Comment 3 David Biesack 2016-02-12 14:20:59 UTC 
Created attachment 121710 [details]
xrestop.log

output of xrestop -b -m 0
after X has been up for a short while (few hours)
Comment 4 David Biesack 2016-02-12 21:34:53 UTC 
Created attachment 121725 [details]
xrestop -b -m 1

Latest output of

  xrestop -b -m 1

Server has now been running
Comment 5 David Biesack 2016-02-12 21:39:04 UTC 
Created attachment 121726 [details]
/sys/kernel/debug/dri/0/i915_gem_objects

Content of /sys/kernel/debug/dri/0/i915_gem_objects file
also after the X server has been up and running for 6+ hours

conky is reporting "Mem 8.25GiB of 15.6GiB (53%)"
via the .conkyrc line "Mem $mem of $memmax ($memperc%)"
Comment 6 Chris Wilson 2016-02-13 16:31:41 UTC 
That looks consistent with 

commit 2d26643cab33a32847afaf13b50d326d09d58bf7
Author: Chris Wilson <chris@chris-wilson.co.uk>
Date:   Fri Nov 13 19:03:36 2015 +0000

    sna/dri2: Drop the reference on the fence when complete
    
    Fixes regression from
    
    commit 8d9e496670f48b4eec64dfe1bcedb49793cf3073
    Author: Chris Wilson <chris@chris-wilson.co.uk>
    Date:   Wed Jul 22 11:14:01 2015 +0100
    
        sna/dri2: Take over the placeholder vblank
    
    After noting the fence was complete, we would clear it. But I forgot
    that we actually held a reference on to it, and so we would leak the 64k
    batch, and starve the system of available memory in about 18 minutes of
    SwapBuffers.
    
    Reported-by: Arkadiusz Miskiewicz <arekm@maven.pl>
    Bugzilla: https://bugs.freedesktop.org/show_bug.cgi?id=92911
    Signed-off-by: Chris Wilson <chris@chris-wilson.co.uk>

*** This bug has been marked as a duplicate of bug 92911 ***

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.