Moving to Nouveau.

Is this still an issue with kernel 3.18?
(The bug went unnoticed as it wasn't linked to Nouveau.)

I just tested on a GeForce 8600 GTS and had no problem loading Nouveau, launching X or kmscon or glxgears. Closing as fixed.

This bug still applies with 3.18.

(In reply to Pierre Moreau from comment #2)
> I just tested on a GeForce 8600 GTS and had no problem loading Nouveau,
> launching X or kmscon or glxgears. Closing as fixed.

Are you testing on a system where the GeForce 8600 GTS is a secondary GPU and not initialized by VBIOS?

If I'm booting on GF as primary GPU nouveau gets along with it. It's in the case I'm booting on the radeon (and GF is thus left alone by BIOS) that things are broken. Modprobe nouveau works, writing to its /dev/fb1 seems to work as well (though monitor connected to GF via VGA cable sees no signal).

Launching kmscon freezes the whole system:
kmscon --drm --gpus=all -v --debug
[0000.000000] NOTICE: kmscon Revision kmscon-8 Oct 26 2014 19:43:16
[0000.000101] INFO: conf: reading config file /etc/kmscon/kmscon.conf
[0000.045957] NOTICE: vt: using tty /dev/tty7
[0000.046126] DEBUG: tsm_screen: new screen (tsm_screen_new() in src/tsm_screen.c:573)
[0000.046152] DEBUG: tsm_vte: new vte object (tsm_vte_new() in src/tsm_vte.c:399)
[0000.399785] INFO: drm_shared: new drm device via /dev/dri/card0
[0000.493045] INFO: video: new device 0x1f67ae0
[0000.493491] INFO: ignoring video device /dev/fb0 on seat seat0 as it is a DRM-fbdev device
[0000.493772] INFO: drm_shared: new drm device via /dev/dri/card1

nothing follows and system does not respond anymore.

(In reply to Bruno from comment #3)
> Are you testing on a system where the GeForce 8600 GTS is a secondary GPU
> and not initialized by VBIOS?

I wasn't, you're right. I'll plug my HD6870 along and see if I can debug this. Does it only go wrong when launching kmscon, or is also the same with X or Wayland?

After fresh boot:
echo 1 > /sys/bus/pci/devices/0000\:01\:00.0/reset


Kernel log for modprobe nouveau debug=0xff:
2014-12-13 18:55:58.978720 +0100 [  108.870465] nouveau 0000:01:00.0: enabling device (0004 -> 0007)
2014-12-13 18:55:58.981262 +0100 [  108.873035] nouveau  [  DEVICE][0000:01:00.0] BOOT0  : 0x084000a2
2014-12-13 18:55:58.982499 +0100 [  108.874310] nouveau  [  DEVICE][0000:01:00.0] Chipset: G84 (NV84)
2014-12-13 18:55:58.983988 +0100 [  108.875563] nouveau  [  DEVICE][0000:01:00.0] Family : NV50
2014-12-13 18:55:58.985544 +0100 [  108.877111] nouveau  [   VBIOS][0000:01:00.0] checking PRAMIN for image...
2014-12-13 18:55:58.988741 +0100 [  108.878603] nouveau  [   VBIOS][0000:01:00.0] ... signature not found
2014-12-13 18:55:58.991752 +0100 [  108.878605] nouveau  [   VBIOS][0000:01:00.0] checking PROM for image...
2014-12-13 18:55:59.087159 +0100 [  108.978689] nouveau  [   VBIOS][0000:01:00.0] ... appears to be valid
2014-12-13 18:55:59.088639 +0100 [  108.980177] nouveau  [   VBIOS][0000:01:00.0] using image from PROM
2014-12-13 18:55:59.090312 +0100 [  108.981855] nouveau  [   VBIOS][0000:01:00.0] BIT signature found
2014-12-13 18:55:59.091785 +0100 [  108.983331] nouveau  [   VBIOS][0000:01:00.0] version 60.84.32.00.00
2014-12-13 18:55:59.094152 +0100 [  108.985929] nouveau  [ DEVINIT][0000:01:00.0] adaptor not initialised
2014-12-13 18:55:59.095419 +0100 [  108.987214] nouveau  [   VBIOS][0000:01:00.0] running init tables
2014-12-13 18:55:59.158069 +0100 [  109.049842] nouveau 0000:01:00.0: irq 38 for MSI/MSI-X
2014-12-13 18:55:59.159330 +0100 [  109.051094] nouveau  [     PMC][0000:01:00.0] MSI interrupts enabled
2014-12-13 18:55:59.160647 +0100 [  109.052409] nouveau  [     PFB][0000:01:00.0] RAM type: GDDR3
2014-12-13 18:55:59.161877 +0100 [  109.053646] nouveau  [     PFB][0000:01:00.0] RAM size: 512 MiB
2014-12-13 18:55:59.163115 +0100 [  109.054884] nouveau  [     PFB][0000:01:00.0]    ZCOMP: 1892 tags
2014-12-13 18:55:59.168203 +0100 [  109.058900] nouveau  [    VOLT][0000:01:00.0] GPU voltage: 1300000uv
2014-12-13 18:55:59.200094 +0100 [  109.089940] nouveau  [  PTHERM][0000:01:00.0] FAN control: PWM
2014-12-13 18:55:59.203197 +0100 [  109.093119] nouveau  [  PTHERM][0000:01:00.0] fan management: automatic
2014-12-13 18:55:59.206283 +0100 [  109.096221] nouveau  [  PTHERM][0000:01:00.0] internal sensor: yes
2014-12-13 18:55:59.229500 +0100 [  109.119368] nouveau  [     CLK][0000:01:00.0] 20: core 675 MHz shader 1450 MHz memory 1008 MHz
2014-12-13 18:55:59.232628 +0100 [  109.122527] nouveau  [     CLK][0000:01:00.0] --: core 675 MHz shader 1458 MHz memory 900 MHz
2014-12-13 18:55:59.236536 +0100 [  109.126342] nouveau E[    PBUS][0000:01:00.0] MMIO write of 0x00000000 FAULT at 0x00fd94
2014-12-13 18:55:59.238046 +0100 [  109.129998] nouveau E[    PBUS][0000:01:00.0] MMIO write of 0x00000000 FAULT at 0x103d94
2014-12-13 18:55:59.239145 +0100 [  109.130208] nouveau  [     DRM] VRAM: 512 MiB
2014-12-13 18:55:59.239932 +0100 [  109.130210] nouveau  [     DRM] GART: 1048576 MiB
2014-12-13 18:55:59.240840 +0100 [  109.130215] nouveau  [     DRM] TMDS table version 2.0
2014-12-13 18:55:59.241762 +0100 [  109.130216] nouveau  [     DRM] DCB version 4.0
2014-12-13 18:55:59.242703 +0100 [  109.130219] nouveau  [     DRM] DCB outp 00: 01000302 00020030
2014-12-13 18:55:59.243647 +0100 [  109.130222] nouveau  [     DRM] DCB outp 01: 02000300 00000028
2014-12-13 18:55:59.244579 +0100 [  109.130223] nouveau  [     DRM] DCB outp 02: 04012310 00000028
2014-12-13 18:55:59.245498 +0100 [  109.130225] nouveau  [     DRM] DCB outp 03: 02021322 00020010
2014-12-13 18:55:59.246404 +0100 [  109.130228] nouveau  [     DRM] DCB conn 00: 1030
2014-12-13 18:55:59.247372 +0100 [  109.130230] nouveau  [     DRM] DCB conn 01: 2161
2014-12-13 18:55:59.248262 +0100 [  109.130232] nouveau  [     DRM] DCB conn 02: 0200
2014-12-13 18:55:59.249077 +0100 [  109.130233] nouveau  [     DRM] DCB conn 03: 0362
2014-12-13 18:55:59.676841 +0100 [  109.568760] Clocksource tsc unstable (delta = -209683361 ns)
2014-12-13 18:56:02.441389 +0100 [  112.331833] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
2014-12-13 18:56:02.443054 +0100 [  112.333514] [drm] Driver supports precise vblank timestamp query.
2014-12-13 18:56:02.465236 +0100 [  112.355653] nouveau  [     DRM] MM: using CRYPT for buffer copies
2014-12-13 18:56:02.466331 +0100 [  112.357576] Switched to clocksource hpet
2014-12-13 18:56:04.057120 +0100 [  113.947900] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000, ch 127
2014-12-13 18:56:04.105137 +0100 [  113.995905] nouveau  [     DRM] allocated 1280x1024 fb: 0x70000, bo ffff880213fe51e0
2014-12-13 18:56:04.106399 +0100 [  113.997151] nouveau 0000:01:00.0: fb1: nouveaufb frame buffer device
2014-12-13 18:56:04.107311 +0100 [  113.998096] [drm] Initialized nouveau 1.2.1 20120801 for 0000:01:00.0 on minor 1

starting Xorg here

2014-12-13 18:57:13.724675 +0100 [  183.597870] vgaarb: device changed decodes: PCI:0000:01:00.0,olddecodes=io+mem,decodes=none:owns=none
2014-12-13 18:57:13.724735 +0100 [  183.597895] vgaarb: device changed decodes: PCI:0000:00:01.0,olddecodes=io+mem,decodes=none:owns=io+mem
2014-12-13 18:57:15.469354 +0100 [  185.342086] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000, ch 1
2014-12-13 18:57:17.474564 +0100 [  187.346760] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:19.547083 +0100 [  189.418737] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:21.547125 +0100 [  191.418262] nouveau E[      VM][0000:01:00.0] vm flush timeout: engine 6
2014-12-13 18:57:23.548308 +0100 [  193.418911] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:25.548260 +0100 [  195.418337] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:27.548232 +0100 [  197.417778] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:29.548328 +0100 [  199.417341] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:31.548318 +0100 [  201.416804] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:33.549819 +0100 [  203.417748] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:35.550309 +0100 [  205.417730] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:37.550646 +0100 [  207.417540] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:39.551014 +0100 [  209.417377] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:41.555729 +0100 [  211.421537] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:43.555982 +0100 [  213.421276] nouveau E[      VM][0000:01:00.0] vm flush timeout: engine 6
2014-12-13 18:57:45.156695 +0100 [  215.021538] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000, ch 1
2014-12-13 18:57:46.747471 +0100 [  216.611900] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000, ch 1
2014-12-13 18:57:48.747555 +0100 [  218.611475] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:50.747571 +0100 [  220.610958] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:52.747572 +0100 [  222.610437] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:54.747576 +0100 [  224.609909] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout
2014-12-13 18:57:56.747582 +0100 [  226.609387] nouveau E[  PGRAPH][0000:01:00.0] vm flush timeout


Xorg log (stderr, started as /usr/bin/Xorg -noreset -nolisten tcp -verbose vt7):
X.Org X Server 1.15.0
Release Date: 2013-12-27
X Protocol Version 11, Revision 0
Build Operating System: Linux 3.17.0-kvm x86_64 Gentoo
Current Operating System: Linux sisyphe 3.18.0-kvm #1 SMP Mon Dec 8 19:05:52 CET 2014 x86_64
Kernel command line: slub_debug=FZP root=/dev/sda2 iommu=1 
Build Date: 26 October 2014  07:21:14PM
 
Current version of pixman: 0.32.4
        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: Sat Dec 13 18:56:58 2014
(==) Using config directory: "/etc/X11/xorg.conf.d"
(==) Using system config directory "/usr/share/X11/xorg.conf.d"
(==) No Layout section.  Using the first Screen section.
(==) No screen section available. Using defaults.
(**) |-->Screen "Default Screen Section" (0)
(**) |   |-->Monitor "<default monitor>"
(==) No device specified for screen "Default Screen Section".
        Using the first device section listed.
(**) |   |-->Device "nvidia"
(==) No monitor specified for screen "Default Screen Section".
        Using a default monitor configuration.
(==) Automatically adding devices
(==) Automatically enabling devices
(==) Automatically adding GPU devices
(WW) The directory "/usr/share/fonts/TTF/" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/OTF/" does not exist.
        Entry deleted from font path.
(WW) The directory "/usr/share/fonts/Type1/" does not exist.
        Entry deleted from font path.
(WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/100dpi/".
        Entry deleted from font path.
        (Run 'mkfontdir' on "/usr/share/fonts/100dpi/").
(WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/75dpi/".
        Entry deleted from font path.
        (Run 'mkfontdir' on "/usr/share/fonts/75dpi/").
(==) FontPath set to:
        /usr/share/fonts/misc/
(==) ModulePath set to "/usr/lib64/xorg/modules"
(II) The server relies on udev to provide the list of input devices.
        If no devices become available, reconfigure udev or disable AutoAddDevices.
(II) xfree86: Adding drm device (/dev/dri/card0)
(II) xfree86: Adding drm device (/dev/dri/card1)
(--) PCI:*(0:0:1:0) 1002:9640:1458:d000 rev 0, Mem @ 0xc0000000/268435456, 0xfdfc0000/262144, I/O @ 0x0000f800/256
(--) PCI: (0:1:0:0) 10de:0400:0000:0000 rev 161, Mem @ 0xfa000000/16777216, 0xd0000000/268435456, 0xf8000000/33554432, I/O @ 0x0000df00/128, BIOS @ 0x????????/131072
Initializing built-in extension Generic Event Extension
Initializing built-in extension SHAPE
Initializing built-in extension MIT-SHM
Initializing built-in extension XInputExtension
Initializing built-in extension XTEST
Initializing built-in extension BIG-REQUESTS
Initializing built-in extension SYNC
Initializing built-in extension XKEYBOARD
Initializing built-in extension XC-MISC
Initializing built-in extension XINERAMA
Initializing built-in extension XFIXES
Initializing built-in extension RENDER
Initializing built-in extension RANDR
Initializing built-in extension COMPOSITE
Initializing built-in extension DAMAGE
Initializing built-in extension MIT-SCREEN-SAVER
Initializing built-in extension DOUBLE-BUFFER
Initializing built-in extension RECORD
Initializing built-in extension DPMS
Initializing built-in extension Present
Initializing built-in extension DRI3
Initializing built-in extension X-Resource
Initializing built-in extension XVideo
Initializing built-in extension XVideo-MotionCompensation
Initializing built-in extension XFree86-VidModeExtension
Initializing built-in extension XFree86-DGA
Initializing built-in extension XFree86-DRI
Initializing built-in extension DRI2
(II) "glx" will be loaded by default.
(II) Loading /usr/lib64/xorg/modules/libglamoregl.so
(II) Module glamoregl: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 0.6.0
(II) Loading /usr/lib64/xorg/modules/extensions/libglx.so
(II) Module glx: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 1.0.0
(==) AIGLX enabled
Loading extension GLX
(II) Loading /usr/lib64/xorg/modules/drivers/nouveau_drv.so
(II) Module nouveau: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 1.0.10
(II) NOUVEAU driver 
(II) NOUVEAU driver for NVIDIA chipset families :
        RIVA TNT        (NV04)
        RIVA TNT2       (NV05)
        GeForce 256     (NV10)
        GeForce 2       (NV11, NV15)
        GeForce 4MX     (NV17, NV18)
        GeForce 3       (NV20)
        GeForce 4Ti     (NV25, NV28)
        GeForce FX      (NV3x)
        GeForce 6       (NV4x)
        GeForce 7       (G7x)
        GeForce 8       (G8x)
        GeForce GTX 200 (NVA0)
        GeForce GTX 400 (NVC0)
(++) using VT number 7
(II) [drm] nouveau interface version: 1.2.1
(--) NOUVEAU(0): Chipset: "NVIDIA NV84"
(II) NOUVEAU(0): Creating default Display subsection in Screen section
        "Default Screen Section" for depth/fbbpp 24/32
(==) NOUVEAU(0): Depth 24, (--) framebuffer bpp 32
(==) NOUVEAU(0): RGB weight 888
(==) NOUVEAU(0): Default visual is TrueColor
(==) NOUVEAU(0): Using HW cursor
(==) NOUVEAU(0): GLX sync to VBlank disabled.
(==) NOUVEAU(0): Page flipping enabled
(==) NOUVEAU(0): Swap limit set to 2 [Max allowed 2]
(II) NOUVEAU(0): Output DVI-I-1 has no monitor section
(II) NOUVEAU(0): Output VGA-1 has no monitor section
(II) NOUVEAU(0): Output HDMI-1 has no monitor section
(II) NOUVEAU(0): EDID for output DVI-I-1
(II) NOUVEAU(0): EDID for output VGA-1
(II) NOUVEAU(0): Manufacturer: CNA  Model: 1701  Serial#: 0
(II) NOUVEAU(0): Year: 2001  Week: 11
(II) NOUVEAU(0): EDID Version: 1.3
(II) NOUVEAU(0): Analog Display Input,  Input Voltage Level: 0.700/0.300 V
(II) NOUVEAU(0): Sync:  Separate
(II) NOUVEAU(0): Max Image Size [cm]: horiz.: 36  vert.: 29
(II) NOUVEAU(0): Gamma: 2.50
(II) NOUVEAU(0): DPMS capabilities: Off; RGB/Color Display
(II) NOUVEAU(0): Default color space is primary color space
(II) NOUVEAU(0): First detailed timing is preferred mode
(II) NOUVEAU(0): GTF timings supported
(II) NOUVEAU(0): redX: 0.624 redY: 0.353   greenX: 0.307 greenY: 0.600
(II) NOUVEAU(0): blueX: 0.151 blueY: 0.136   whiteX: 0.312 whiteY: 0.343
(II) NOUVEAU(0): Supported established timings:
(II) NOUVEAU(0): 720x400@70Hz
(II) NOUVEAU(0): 640x480@60Hz
(II) NOUVEAU(0): 640x480@75Hz
(II) NOUVEAU(0): 800x600@60Hz
(II) NOUVEAU(0): 800x600@75Hz
(II) NOUVEAU(0): 832x624@75Hz
(II) NOUVEAU(0): 1024x768@60Hz
(II) NOUVEAU(0): 1024x768@70Hz
(II) NOUVEAU(0): 1024x768@75Hz
(II) NOUVEAU(0): 1280x1024@75Hz
(II) NOUVEAU(0): 1152x864@75Hz
(II) NOUVEAU(0): Manufacturer's mask: 0
(II) NOUVEAU(0): Supported standard timings:
(II) NOUVEAU(0): #0: hsize: 640  vsize 480  refresh: 75  vid: 20273
(II) NOUVEAU(0): #1: hsize: 800  vsize 600  refresh: 75  vid: 20293
(II) NOUVEAU(0): #2: hsize: 1024  vsize 768  refresh: 75  vid: 20321
(II) NOUVEAU(0): #3: hsize: 1280  vsize 1024  refresh: 75  vid: 36737
(II) NOUVEAU(0): #4: hsize: 1280  vsize 960  refresh: 60  vid: 16513
(II) NOUVEAU(0): Supported detailed timing:
(II) NOUVEAU(0): clock: 108.0 MHz   Image Size:  360 x 290 mm
(II) NOUVEAU(0): h_active: 1280  h_sync: 1328  h_sync_end 1440 h_blank_end 1688 h_border: 0
(II) NOUVEAU(0): v_active: 1024  v_sync: 1025  v_sync_end 1028 v_blanking: 1066 v_border: 0
(II) NOUVEAU(0): Ranges: V min: 56 V max: 76 Hz, H min: 31 H max: 80 kHz, PixClock max 145 MHz
(II) NOUVEAU(0): Monitor name: 17TFT LCD MNT
(II) NOUVEAU(0):  20020513
(II) NOUVEAU(0): EDID (in hex):
(II) NOUVEAU(0):        00ffffffffffff000dc1011700000000
(II) NOUVEAU(0):        0b0b010308241d962fdaf39f5a4e9926
(II) NOUVEAU(0):        225057a56f80314f454f614f818f8140
(II) NOUVEAU(0):        010101010101302a009851002a403070
(II) NOUVEAU(0):        1300682211000018000000fd00384c1f
(II) NOUVEAU(0):        500e000a202020202020000000fc0031
(II) NOUVEAU(0):        37544654204c4344204d4e54000000fe
(II) NOUVEAU(0):        003230303230353133202020202000d3
(II) NOUVEAU(0): Printing probed modes for output VGA-1
(II) NOUVEAU(0): Modeline "1280x1024"x60.0  108.00  1280 1328 1440 1688  1024 1025 1028 1066 -hsync -vsync (64.0 kHz eP)
(II) NOUVEAU(0): Modeline "1280x1024"x75.0  135.00  1280 1296 1440 1688  1024 1025 1028 1066 +hsync +vsync (80.0 kHz e)
(II) NOUVEAU(0): Modeline "1280x960"x60.0  108.00  1280 1376 1488 1800  960 961 964 1000 +hsync +vsync (60.0 kHz e)
(II) NOUVEAU(0): Modeline "1280x800"x74.9  106.50  1280 1360 1488 1696  800 803 809 838 -hsync +vsync (62.8 kHz e)
(II) NOUVEAU(0): Modeline "1280x800"x59.8   83.50  1280 1352 1480 1680  800 803 809 831 +hsync -vsync (49.7 kHz e)
(II) NOUVEAU(0): Modeline "1152x864"x75.0  108.00  1152 1216 1344 1600  864 865 868 900 +hsync +vsync (67.5 kHz e)
(II) NOUVEAU(0): Modeline "1280x768"x74.9  102.25  1280 1360 1488 1696  768 771 778 805 +hsync -vsync (60.3 kHz e)
(II) NOUVEAU(0): Modeline "1280x768"x59.9   79.50  1280 1344 1472 1664  768 771 778 798 -hsync +vsync (47.8 kHz e)
(II) NOUVEAU(0): Modeline "1024x768"x75.1   78.80  1024 1040 1136 1312  768 769 772 800 +hsync +vsync (60.1 kHz e)
(II) NOUVEAU(0): Modeline "1024x768"x75.0   78.75  1024 1040 1136 1312  768 769 772 800 +hsync +vsync (60.0 kHz e)
(II) NOUVEAU(0): Modeline "1024x768"x70.1   75.00  1024 1048 1184 1328  768 771 777 806 -hsync -vsync (56.5 kHz e)
(II) NOUVEAU(0): Modeline "1024x768"x60.0   65.00  1024 1048 1184 1344  768 771 777 806 -hsync -vsync (48.4 kHz e)
(II) NOUVEAU(0): Modeline "1024x576"x60.0   46.97  1024 1064 1168 1312  576 577 580 597 -hsync +vsync (35.8 kHz)
(II) NOUVEAU(0): Modeline "832x624"x74.6   57.28  832 864 928 1152  624 625 628 667 -hsync -vsync (49.7 kHz e)
(II) NOUVEAU(0): Modeline "800x600"x72.2   50.00  800 856 976 1040  600 637 643 666 +hsync +vsync (48.1 kHz e)
(II) NOUVEAU(0): Modeline "800x600"x75.0   49.50  800 816 896 1056  600 601 604 625 +hsync +vsync (46.9 kHz e)
(II) NOUVEAU(0): Modeline "800x600"x60.3   40.00  800 840 968 1056  600 601 605 628 +hsync +vsync (37.9 kHz e)
(II) NOUVEAU(0): Modeline "800x600"x56.2   36.00  800 824 896 1024  600 601 603 625 +hsync +vsync (35.2 kHz e)
(II) NOUVEAU(0): Modeline "848x480"x60.0   33.75  848 864 976 1088  480 486 494 517 +hsync +vsync (31.0 kHz e)
(II) NOUVEAU(0): Modeline "640x480"x75.0   31.50  640 656 720 840  480 481 484 500 -hsync -vsync (37.5 kHz e)
(II) NOUVEAU(0): Modeline "640x480"x72.8   31.50  640 664 704 832  480 489 492 520 -hsync -vsync (37.9 kHz e)
(II) NOUVEAU(0): Modeline "640x480"x60.0   25.20  640 656 752 800  480 490 492 525 -hsync -vsync (31.5 kHz e)
(II) NOUVEAU(0): Modeline "720x400"x70.1   28.32  720 738 846 900  400 412 414 449 -hsync +vsync (31.5 kHz e)
(II) NOUVEAU(0): EDID for output HDMI-1
(II) NOUVEAU(0): Output DVI-I-1 disconnected
(II) NOUVEAU(0): Output VGA-1 connected
(II) NOUVEAU(0): Output HDMI-1 disconnected
(II) NOUVEAU(0): Using exact sizes for initial modes
(II) NOUVEAU(0): Output VGA-1 using initial mode 1280x1024
(II) NOUVEAU(0): Using default gamma of (1.0, 1.0, 1.0) unless otherwise stated.
(--) NOUVEAU(0): Virtual size is 1280x1024 (pitch 0)
(**) NOUVEAU(0):  Driver mode "1280x1024": 108.0 MHz (scaled from 0.0 MHz), 64.0 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "1280x1024"x60.0  108.00  1280 1328 1440 1688  1024 1025 1028 1066 -hsync -vsync (64.0 kHz eP)
(**) NOUVEAU(0):  Driver mode "1280x1024": 135.0 MHz (scaled from 0.0 MHz), 80.0 kHz, 75.0 Hz
(II) NOUVEAU(0): Modeline "1280x1024"x75.0  135.00  1280 1296 1440 1688  1024 1025 1028 1066 +hsync +vsync (80.0 kHz e)
(**) NOUVEAU(0):  Driver mode "1280x960": 108.0 MHz (scaled from 0.0 MHz), 60.0 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "1280x960"x60.0  108.00  1280 1376 1488 1800  960 961 964 1000 +hsync +vsync (60.0 kHz e)
(**) NOUVEAU(0):  Driver mode "1280x800": 106.5 MHz (scaled from 0.0 MHz), 62.8 kHz, 74.9 Hz
(II) NOUVEAU(0): Modeline "1280x800"x74.9  106.50  1280 1360 1488 1696  800 803 809 838 -hsync +vsync (62.8 kHz e)
(**) NOUVEAU(0):  Driver mode "1280x800": 83.5 MHz (scaled from 0.0 MHz), 49.7 kHz, 59.8 Hz
(II) NOUVEAU(0): Modeline "1280x800"x59.8   83.50  1280 1352 1480 1680  800 803 809 831 +hsync -vsync (49.7 kHz e)
(**) NOUVEAU(0):  Driver mode "1152x864": 108.0 MHz (scaled from 0.0 MHz), 67.5 kHz, 75.0 Hz
(II) NOUVEAU(0): Modeline "1152x864"x75.0  108.00  1152 1216 1344 1600  864 865 868 900 +hsync +vsync (67.5 kHz e)
(**) NOUVEAU(0):  Driver mode "1280x768": 102.2 MHz (scaled from 0.0 MHz), 60.3 kHz, 74.9 Hz
(II) NOUVEAU(0): Modeline "1280x768"x74.9  102.25  1280 1360 1488 1696  768 771 778 805 +hsync -vsync (60.3 kHz e)
(**) NOUVEAU(0):  Driver mode "1280x768": 79.5 MHz (scaled from 0.0 MHz), 47.8 kHz, 59.9 Hz
(II) NOUVEAU(0): Modeline "1280x768"x59.9   79.50  1280 1344 1472 1664  768 771 778 798 -hsync +vsync (47.8 kHz e)
(**) NOUVEAU(0):  Driver mode "1024x768": 78.8 MHz (scaled from 0.0 MHz), 60.1 kHz, 75.1 Hz
(II) NOUVEAU(0): Modeline "1024x768"x75.1   78.80  1024 1040 1136 1312  768 769 772 800 +hsync +vsync (60.1 kHz e)
(**) NOUVEAU(0):  Driver mode "1024x768": 78.8 MHz (scaled from 0.0 MHz), 60.0 kHz, 75.0 Hz
(II) NOUVEAU(0): Modeline "1024x768"x75.0   78.75  1024 1040 1136 1312  768 769 772 800 +hsync +vsync (60.0 kHz e)
(**) NOUVEAU(0):  Driver mode "1024x768": 75.0 MHz (scaled from 0.0 MHz), 56.5 kHz, 70.1 Hz
(II) NOUVEAU(0): Modeline "1024x768"x70.1   75.00  1024 1048 1184 1328  768 771 777 806 -hsync -vsync (56.5 kHz e)
(**) NOUVEAU(0):  Driver mode "1024x768": 65.0 MHz (scaled from 0.0 MHz), 48.4 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "1024x768"x60.0   65.00  1024 1048 1184 1344  768 771 777 806 -hsync -vsync (48.4 kHz e)
(**) NOUVEAU(0):  Mode "1024x576": 47.0 MHz (scaled from 0.0 MHz), 35.8 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "1024x576"x60.0   46.97  1024 1064 1168 1312  576 577 580 597 -hsync +vsync (35.8 kHz)
(**) NOUVEAU(0):  Driver mode "832x624": 57.3 MHz (scaled from 0.0 MHz), 49.7 kHz, 74.6 Hz
(II) NOUVEAU(0): Modeline "832x624"x74.6   57.28  832 864 928 1152  624 625 628 667 -hsync -vsync (49.7 kHz e)
(**) NOUVEAU(0):  Driver mode "800x600": 50.0 MHz (scaled from 0.0 MHz), 48.1 kHz, 72.2 Hz
(II) NOUVEAU(0): Modeline "800x600"x72.2   50.00  800 856 976 1040  600 637 643 666 +hsync +vsync (48.1 kHz e)
(**) NOUVEAU(0):  Driver mode "800x600": 49.5 MHz (scaled from 0.0 MHz), 46.9 kHz, 75.0 Hz
(II) NOUVEAU(0): Modeline "800x600"x75.0   49.50  800 816 896 1056  600 601 604 625 +hsync +vsync (46.9 kHz e)
(**) NOUVEAU(0):  Driver mode "800x600": 40.0 MHz (scaled from 0.0 MHz), 37.9 kHz, 60.3 Hz
(II) NOUVEAU(0): Modeline "800x600"x60.3   40.00  800 840 968 1056  600 601 605 628 +hsync +vsync (37.9 kHz e)
(**) NOUVEAU(0):  Driver mode "800x600": 36.0 MHz (scaled from 0.0 MHz), 35.2 kHz, 56.2 Hz
(II) NOUVEAU(0): Modeline "800x600"x56.2   36.00  800 824 896 1024  600 601 603 625 +hsync +vsync (35.2 kHz e)
(**) NOUVEAU(0):  Driver mode "848x480": 33.8 MHz (scaled from 0.0 MHz), 31.0 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "848x480"x60.0   33.75  848 864 976 1088  480 486 494 517 +hsync +vsync (31.0 kHz e)
(**) NOUVEAU(0):  Driver mode "640x480": 31.5 MHz (scaled from 0.0 MHz), 37.5 kHz, 75.0 Hz
(II) NOUVEAU(0): Modeline "640x480"x75.0   31.50  640 656 720 840  480 481 484 500 -hsync -vsync (37.5 kHz e)
(**) NOUVEAU(0):  Driver mode "640x480": 31.5 MHz (scaled from 0.0 MHz), 37.9 kHz, 72.8 Hz
(II) NOUVEAU(0): Modeline "640x480"x72.8   31.50  640 664 704 832  480 489 492 520 -hsync -vsync (37.9 kHz e)
(**) NOUVEAU(0):  Driver mode "640x480": 25.2 MHz (scaled from 0.0 MHz), 31.5 kHz, 60.0 Hz
(II) NOUVEAU(0): Modeline "640x480"x60.0   25.20  640 656 752 800  480 490 492 525 -hsync -vsync (31.5 kHz e)
(**) NOUVEAU(0):  Driver mode "720x400": 28.3 MHz (scaled from 0.0 MHz), 31.5 kHz, 70.1 Hz
(II) NOUVEAU(0): Modeline "720x400"x70.1   28.32  720 738 846 900  400 412 414 449 -hsync +vsync (31.5 kHz e)
(==) NOUVEAU(0): DPI set to (96, 96)
(II) Loading /usr/lib64/xorg/modules/libfb.so
(II) Module fb: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 1.0.0
(II) Loading /usr/lib64/xorg/modules/libexa.so
(II) Module exa: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 2.6.0
(II) Loading /usr/lib64/xorg/modules/libshadowfb.so
(II) Module shadowfb: vendor="X.Org Foundation"
        compiled for 1.15.0, module version = 1.0.0
(--) Depth 24 pixmap format is 32 bpp
(II) NOUVEAU(0): Opened GPU channel 0
(II) NOUVEAU(0): [DRI2] Setup complete
(II) NOUVEAU(0): [DRI2]   DRI driver: nouveau
(II) NOUVEAU(0): [DRI2]   VDPAU driver: nouveau
(II) EXA(0): Driver allocated offscreen pixmaps
(II) EXA(0): Driver registered support for the following operations:
(II)         Solid
(II)         Copy
(II)         Composite (RENDER acceleration)
(II)         UploadToScreen
(II)         DownloadFromScreen
(==) NOUVEAU(0): Backing store enabled
(==) NOUVEAU(0): Silken mouse enabled
(II) NOUVEAU(0): [XvMC] Associated with Nouveau GeForce 8/9 Textured Video.
(II) NOUVEAU(0): [XvMC] Extension initialized.
(==) NOUVEAU(0): DPMS enabled
(II) NOUVEAU(0): RandR 1.2 enabled, ignore the following RandR disabled message.
(--) RandR disabled
(II) AIGLX: enabled GLX_MESA_copy_sub_buffer
(II) AIGLX: enabled GLX_ARB_create_context
(II) AIGLX: enabled GLX_ARB_create_context_profile
(II) AIGLX: enabled GLX_EXT_create_context_es2_profile
(II) AIGLX: enabled GLX_INTEL_swap_event
(II) AIGLX: enabled GLX_SGI_swap_control and GLX_MESA_swap_control
(II) AIGLX: enabled GLX_EXT_framebuffer_sRGB
(II) AIGLX: enabled GLX_ARB_fbconfig_float
(II) AIGLX: GLX_EXT_texture_from_pixmap backed by buffer objects
(II) AIGLX: Loaded and initialized nouveau
(II) GLX: Initialized DRI2 GL provider for screen 0

<system hung>

Sorry, I didn't had much time to look into it...
I'm currently tracking some similar problems on my G96, which is a secondary GPU. Hopefully, if I manage to solve it, the patch will help you too.

(In reply to Bruno from comment #5)
> After fresh boot:
> echo 1 > /sys/bus/pci/devices/0000\:01\:00.0/reset

Does adding an `echo 1 > /sys/bus/pci/devices/0000\:01\:00.0/rescan` after the reset changes something?

You hit some PFIFO interrupt 0x00200000... Btw, which kernel version was this log taken from? Could you please take another log using the reset/rescan trick with nouveau.debug=debug? The debug argument of Nouveau takes a string (allowed values are "fatal", "error", "warn", "info", "debug", "trace", "paranoia" and "spam"), so your 0xff didn't worked.
It's likely that the PFIFO interrupt is unrelated to the "unable to handle kernel paging request" problem, so you should consider opening a new bug report for it.

Created attachment 114730 [details]
dmesg with 3.19

Sorry for rather late reply, so no issue you being slow too.

(In reply to Pierre Moreau from comment #6)
> Sorry, I didn't had much time to look into it...
> I'm currently tracking some similar problems on my G96, which is a secondary
> GPU. Hopefully, if I manage to solve it, the patch will help you too.
> 
> (In reply to Bruno from comment #5)
> > After fresh boot:
> > echo 1 > /sys/bus/pci/devices/0000\:01\:00.0/reset
> 
> Does adding an `echo 1 > /sys/bus/pci/devices/0000\:01\:00.0/rescan` after
> the reset changes something?

Seen no change in behavior.

> You hit some PFIFO interrupt 0x00200000... Btw, which kernel version was
> this log taken from?

Should have been 3.18 looking at the bug history.

> Could you please take another log using the reset/rescan trick with nouveau.debug=debug?
> The debug argument of Nouveau takes a string (allowed values are "fatal",
> "error", "warn", "info", "debug", "trace", "paranoia" and "spam"),
> so your 0xff didn't worked.

Retried with `modprobe nouveau debug=debug`
Full dmesg attached.

> It's likely that the PFIFO interrupt is unrelated to the "unable to handle
> kernel paging request" problem, so you should consider opening a new bug
> report for it.

As previously, no (real) badness by just modprobing nouveau.

Starting Xorg on top of it (though while having other Xorg running on radeon, but from local linux console) gets my a stuck Xorg:
[<ffffffff815704ac>] rpm_resume+0x18c/0x5c0
[<ffffffff81570928>] __pm_runtime_resume+0x48/0x70
[<ffffffffa00914af>] nouveau_drm_open+0x3f/0x230 [nouveau]
[<ffffffff8144123d>] drm_open+0x1ad/0x4b0
[<ffffffff81447711>] drm_stub_open+0xb1/0x130
[<ffffffff811c6371>] chrdev_open+0xb1/0x190
[<ffffffff811bf702>] do_dentry_open.isra.18+0x1f2/0x320
[<ffffffff811bf8a1>] vfs_open+0x41/0x50
[<ffffffff811cd056>] do_last.isra.59+0x266/0xf20
[<ffffffff811d0329>] path_openat+0x89/0x5a0
[<ffffffff811d18ae>] do_filp_open+0x3e/0xa0
[<ffffffff811c0eee>] do_sys_open+0x12e/0x230
[<ffffffff811c1009>] SyS_open+0x19/0x20
[<ffffffff81861252>] system_call_fastpath+0x12/0x17
[<ffffffffffffffff>] 0xffffffffffffffff

That Xorg's log is rather boring with 55 lines ending with:
[   835.442] (==) ModulePath set to "/usr/lib64/xorg/modules"
[   835.442] (II) The server relies on udev to provide the list of input devices.
        If no devices become available, reconfigure udev or disable AutoAddDevices.
[   835.442] (II) Loader magic: 0x812c80
[   835.442] (II) Module ABI versions:
[   835.442]    X.Org ANSI C Emulation: 0.4
[   835.442]    X.Org Video Driver: 18.0
[   835.442]    X.Org XInput driver : 21.0
[   835.442]    X.Org Server Extension : 8.0
[   835.443] (II) xfree86: Adding drm device (/dev/dri/card0)
[   835.444] (II) xfree86: Adding drm device (/dev/dri/card1)


From Xorg's kernel-side stack it looks stuck in runtime-PM. Will retry with runpm=0 for safety... (and then also with 4.0-rc6)

Created attachment 114731 [details]
dmesg with 3.19 but runpm=0

Same kernel, kernel log starting at loading nouveau with `modprobe nouveau debug=debug runpm=0` and running Xorg on top of it:

Xorg.0.log tail:
[   235.286]    ABI class: X.Org ANSI C Emulation, version 0.4
[   235.286] (II) Loading sub module "shadowfb"
[   235.286] (II) LoadModule: "shadowfb"
[   235.286] (II) Loading /usr/lib64/xorg/modules/libshadowfb.so
[   235.286] (II) Module shadowfb: vendor="X.Org Foundation"
[   235.286]    compiled for 1.16.4, module version = 1.0.0
[   235.286]    ABI class: X.Org ANSI C Emulation, version 0.4
[   235.286] (--) Depth 24 pixmap format is 32 bpp
[   235.294] (II) NOUVEAU(0): Channel setup complete.
[   257.296] (EE) NOUVEAU(0): [COPY] failed to allocate class.

Once Xorg is started system gets sluggish until it completely freezes (even netconsole can't send more data). This kernel load was gathered via netconsole while the previous one was collected locally.
As system froze, I have no state information for Xorg.

Created attachment 114735 [details]
4.0-rc6 dmesg of nouveau loading (debug, runpm=0)

4.0-rc6 is even worse as it dies shortly after modprobing nouveau (even before I have the opportunity to launch Xorg).

Results are the same with echo 1 > reset && echo 1 > rescan as without.

Note that nouveau continues spitting out fan update messages after userspace is dead but eventually stops as well.

(In reply to Bruno from comment #9)
> Created attachment 114735 [details]
> 4.0-rc6 dmesg of nouveau loading (debug, runpm=0)

The first BUG happens in evo_wait() at line 420 of nv50_display.c

Seems like dmac->ptr[put] is bad.

413: evo_wait(void *evoc, int nr)
414: {
415:     struct nv50_dmac *dmac = evoc;
416:     u32 put = nvif_rd32(&dmac->base.user, 0x0000) / 4;
417:
418:     mutex_lock(&dmac->lock);
419:     if (put + nr >= (PAGE_SIZE / 4) - 8) {
420:         dmac->ptr[put] = 0x20000000;
421:
422:         nvif_wr32(&dmac->base.user, 0x0000, 0x00000000);
423:         if (!nvxx_wait(&dmac->base.user, 0x0004, ~0, 0x00000000)) {
424:             mutex_unlock(&dmac->lock);
425:             nv_error(nvxx_object(&dmac->base.user), "channel stalled\n");
426:             return NULL;
427:         }
428:
429:         put = 0;
430:     }
431:
432:     return dmac->ptr + put;
433: }

In ASM:
180:       55                      push   %rbp
181:       48 89 e5                mov    %rsp,%rbp
184:       48 83 ec 20             sub    $0x20,%rsp
188:       48 89 5d e0             mov    %rbx,-0x20(%rbp)
18c:       4c 89 75 f8             mov    %r14,-0x8(%rbp)
190:       48 89 fb                mov    %rdi,%rbx
193:       4c 89 65 e8             mov    %r12,-0x18(%rbp)
197:       4c 89 6d f0             mov    %r13,-0x10(%rbp)
19b:       41 89 f6                mov    %esi,%r14d
19e:       48 8b 47 08             mov    0x8(%rdi),%rax
1a2:       48 8b 78 40             mov    0x40(%rax),%rdi
1a6:       48 85 ff                test   %rdi,%rdi
1a9:       0f 84 e1 00 00 00       je     290 <evo_wait+0x110>
1af:       e8 00 00 00 00          callq  1b4 <evo_wait+0x34>
1b4:       c1 e8 02                shr    $0x2,%eax
1b7:       4c 8d ab 00 01 00 00    lea    0x100(%rbx),%r13
1be:       41 89 c4                mov    %eax,%r12d
1c1:       4c 89 ef                mov    %r13,%rdi
1c4:       45 01 e6                add    %r12d,%r14d
1c7:       e8 00 00 00 00          callq  1cc <evo_wait+0x4c>
1cc:       41 81 fe f7 03 00 00    cmp    $0x3f7,%r14d
1d3:       0f 86 87 00 00 00       jbe    260 <evo_wait+0xe0>
1d9:       48 8b 43 58             mov    0x58(%rbx),%rax
1dd:       42 c7 04 a0 00 00 00    movl   $0x20000000,(%rax,%r12,4)
           ^
1e4:       20
...

From the trace:
 access at 0xffff880313207ffc  (rax - 4)
r12 = 0x000000003fffffff
rax = 0xffff880213208000

Decoded data:
dmac@ffff880214419b70->ptr[put=1073741823] = 0x20000000

That would mean that nvif_rd32(&dmac->base.user, 0x0000) returns 0xffffffff. That value looks rather "non-initialized" and GPU's PCI bar surly is not 4GB large.

*** Bug 86537 has been marked as a duplicate of this bug. ***

Closing my own bug request as it duplicates this one.

I wish I knew which engine to blame as it would make debugging so much easier; all the obvious ones don't seem to be responsible!

(In reply to Bruno from comment #10)
> That would mean that nvif_rd32(&dmac->base.user, 0x0000) returns 0xffffffff.
> That value looks rather "non-initialized" and GPU's PCI bar surly is not 4GB
> large.

Nouveau first configures some parameters on the EVO Core channel, and the returned DMAC put pointer is valid at that time. Same goes when it configures some flip stuff on the EVO Base channel. However, as soon as it pushes the flip stuff, the next returned DMAC put pointer is garbage.
Iirc, when I commented the whole content of the nv50_display_flip_stop, the crash occured just after kicking the result of nv50_display_flip_next. So most likely something related to flipping but I have no idea what is needed for flipping to work.

@Bruno
Could you try bisecting which commit made it worse in 4.0-rc6? I'll have some time this week-end to get back to it.

Could you please run `nvapeek 8841c` and post the result here please? If you get a non-zero value v, write value = (v & 0xffffff00) to 8841c using `nvapoke 8841c value` **before** loading Nouveau. nvapeek and nvapoke are found in the envytools repo: https://github.com/envytools/envytools

This works on my laptop---finally no more evo_wait errors---, but it remains a temporary fix.
If it works on your machine too, I'll probably ask you for a mmiotrace of the blob, except if I write and get some answers from Nvidia, so no rush. :-)

Created attachment 116181 [details]
linux-4.0, + modprobe nouveau debug=debug runpm=0

(In reply to Pierre Moreau from comment #14)
> Could you please run `nvapeek 8841c` and post the result here please?

./envytools/nva/nvapeek 8841c
0008841c: 00000400

> If you get a non-zero value v, write value = (v & 0xffffff00) to 8841c using
> `nvapoke 8841c value` **before** loading Nouveau. nvapeek and nvapoke are
> found in the envytools repo: https://github.com/envytools/envytools

modprobe nouveau
[  747.279238] nouveau  [  DEVICE][0000:01:00.0] BOOT0  : 0x084000a2
[  747.279252] nouveau  [  DEVICE][0000:01:00.0] Chipset: G84 (NV84)
[  747.279257] nouveau  [  DEVICE][0000:01:00.0] Family : NV50
[  747.379554] nouveau  [   VBIOS][0000:01:00.0] using image from PROM
[  747.379705] nouveau  [   VBIOS][0000:01:00.0] BIT signature found
[  747.379710] nouveau  [   VBIOS][0000:01:00.0] version 60.84.32.00.00
[  747.381119] nouveau  [ DEVINIT][0000:01:00.0] adaptor not initialised
[  747.381140] nouveau  [   VBIOS][0000:01:00.0] running init tables
[  747.442531] nouveau  [     PMC][0000:01:00.0] MSI interrupts enabled
[  747.442593] nouveau  [     PFB][0000:01:00.0] RAM type: GDDR3
[  747.442597] nouveau  [     PFB][0000:01:00.0] RAM size: 512 MiB
[  747.442601] nouveau  [     PFB][0000:01:00.0]    ZCOMP: 1892 tags
[  747.445672] nouveau  [    VOLT][0000:01:00.0] GPU voltage: 1300000uv
[  747.474545] nouveau  [  PTHERM][0000:01:00.0] FAN control: PWM
[  747.474597] nouveau  [  PTHERM][0000:01:00.0] fan management: automatic
[  747.474644] nouveau  [  PTHERM][0000:01:00.0] internal sensor: yes
[  747.494680] nouveau  [     CLK][0000:01:00.0] 20: core 675 MHz shader 1450 MHz memory 1008 MHz
[  747.494743] nouveau  [     CLK][0000:01:00.0] --: core 675 MHz shader 1458 MHz memory 900 MHz
[  747.495846] nouveau E[    PBUS][0000:01:00.0] MMIO write of 0x00000000 FAULT at 0x103d94
[  747.496017] nouveau  [     DRM] VRAM: 512 MiB
[  747.496049] nouveau  [     DRM] GART: 1048576 MiB
[  747.496067] nouveau  [     DRM] TMDS table version 2.0
[  747.496079] nouveau  [     DRM] DCB version 4.0
[  747.496096] nouveau  [     DRM] DCB outp 00: 01000302 00020030
[  747.496124] nouveau  [     DRM] DCB outp 01: 02000300 00000028
[  747.496138] nouveau  [     DRM] DCB outp 02: 04012310 00000028
[  747.496150] nouveau  [     DRM] DCB outp 03: 02021322 00020010
[  747.496161] nouveau  [     DRM] DCB conn 00: 1030
[  747.496184] nouveau  [     DRM] DCB conn 01: 2161
[  747.496202] nouveau  [     DRM] DCB conn 02: 0200
[  747.496218] nouveau  [     DRM] DCB conn 03: 0362
[  747.525704] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[  747.525721] [drm] Driver supports precise vblank timestamp query.
[  747.973933] Clocksource tsc unstable (delta = -209664716 ns)
[  749.145223] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000
[  749.145653] nouveau  [     DRM] MM: using CRYPT for buffer copies
[  749.145754] Switched to clocksource hpet
[  750.783934] nouveau  [     DRM] allocated 1280x1024 fb: 0x70000, bo ffff8800beb1b538
[  750.784312] nouveau 0000:01:00.0: fb1: nouveaufb frame buffer device
[  750.784344] [drm] Initialized nouveau 1.2.1 20120801 for 0000:01:00.0 on minor 1

After this X11 gets stuck in runtime-PM:
[<ffffffff8158358c>] rpm_resume+0x18c/0x5c0
[<ffffffff81583a08>] __pm_runtime_resume+0x48/0x70
[<ffffffffa0090d63>] nouveau_drm_open+0x43/0x220 [nouveau]
[<ffffffff814512cd>] drm_open+0x1ad/0x4b0
[<ffffffff81457811>] drm_stub_open+0xb1/0x130
[<ffffffff811cb4a1>] chrdev_open+0xb1/0x190
[<ffffffff811c4751>] do_dentry_open.isra.19+0x101/0x330
[<ffffffff811c49f1>] vfs_open+0x41/0x50
[<ffffffff811d462b>] do_last.isra.56+0x25b/0xf20
[<ffffffff811d5379>] path_openat+0x89/0x5a0
[<ffffffff811d6c0e>] do_filp_open+0x3e/0xa0
[<ffffffff811c5f4e>] do_sys_open+0x12e/0x230
[<ffffffff811c6069>] SyS_open+0x19/0x20
[<ffffffff81879a72>] system_call_fastpath+0x12/0x17
[<ffffffffffffffff>] 0xffffffffffffffff

With runpm=0 things behave somewhat better (display lights up - tested on DVI output where earlier tests were with VGA).

Though once X has started system gets sluggish (periods of "freeze" and continue) until it fully freezes.

Hum... So the evo_wait error disappeared for some reason and you're back to the behaviour from 3.19.

So, from the runpm=0:
[   91.530766] nouveau W[   PFIFO][0000:01:00.0] unknown intr 0x00200000
[...]
[  101.568583] nouveau E[   PDISP][0000:01:00.0][0xc000827c] channel stalled
[...]
[  183.835874] nouveau E[     PGR][0000:01:00.0] vm flush timeout

I won't have time to have a look at the old behaviour before a few months sadly...

Created attachment 116184 [details]
linux-4.1-rc5+

Same result as previous kernel (though screen never detected signal), this time with 4.1-rc5+, but not doing the nvapeek/nvapoke.

System freeze some time after starting Xorg.

Created attachment 116185 [details]
linux-4.1-rc5+

Same 4.1-rc5+ kernel but this time around performing nvapeek/nvapoke.

Doing the peek/poke makes display get signal over DVI but does not prevent the system from freezing after X has started.

The last lines of output from X were (the COPY failure coming some time after the rest):
(--) Depth 24 pixmap format is 32 bpp
(II) NOUVEAU(0): Channel setup complete.
(EE) NOUVEAU(0): [COPY] failed to allocate class.


The PGRAPH/VM FLUSH timeouts look like they could have something to do with the freeze and be cause of X COPY failure.

I had completely forgotten I had the same card... So, tried again with 4.1.5 and with config=NvForcePost=1, and I get the same evo_wait bug "Unable to handle kernel paging request" that you had at some point, even if the card is the primary GPU. Removing config=NvForcePost=1, and the bug disappears.

I'll try to do some testing with this configuration (single GPU + config=NvForcePost=1), see if I can find something, before putting the G84 back as a secondary GPU.

(In reply to Bruno from comment #18)
> Created attachment 116185 [details]
> linux-4.1-rc5+
> 
> Same 4.1-rc5+ kernel but this time around performing nvapeek/nvapoke.
> 
> Doing the peek/poke makes display get signal over DVI but does not prevent
> the system from freezing after X has started.
> 
> The last lines of output from X were (the COPY failure coming some time
> after the rest):
> (--) Depth 24 pixmap format is 32 bpp
> (II) NOUVEAU(0): Channel setup complete.
> (EE) NOUVEAU(0): [COPY] failed to allocate class.

Expected. Your GPU doesn't have COPY engine.

> 
> 
> The PGRAPH/VM FLUSH timeouts look like they could have something to do with
> the freeze and be cause of X COPY failure.

Recent kernels should fix the PDISP hang error, which in turn might avoid tickling the vm lockup. Can you test a kernel that includes 697bb728d9e (v4.2 should have it).

I tested with latest Nouveau and latest drm-next as of yesterday (4.6-rcX), with the G84 alongside a GM206; the GM206 was the one driving the screen. Launching weston or kmscon works, but starting X makes the whole computer freeze (no responce on key presses, power button press, capslock not working). I’ll try to setup netconsole and SSH later to gather some logs.

On one of the boots, I got a slow path warning in nvkm_pci_intr()/nvkm_mc_intr().

Trying to suspend the computer simply results in the computer not suspending and freezing.

And using the G84 for driving the screen results in the display receiving no signal from the GPU (over VGA (using a VGA->DVI adapter) or HDMI). In the logs, the card did found the screen and initialised a fb of the correct resolution; they are no errors to be found.

Starting X still results in a freeze of the computer.

s/they are no/there are no --"

With the GM206 driving the display, no special Nouveau options, I get this upon starting X:

[  545.904188] vgaarb: device changed decodes: PCI:0000:04:00.0,olddecodes=io+mem,decodes=none:owns=none
[  548.670462] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/engine/gr/g84.c:169/g84_gr_tlb_flush()!
[  550.670606] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/engine/gr/g84.c:169/g84_gr_tlb_flush()!
[  552.670884] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/engine/gr/g84.c:169/g84_gr_tlb_flush()!
[  554.671015] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/engine/gr/g84.c:169/g84_gr_tlb_flush()!
[  556.671164] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/engine/gr/g84.c:169/g84_gr_tlb_flush()!
[  557.490345] hrtimer: interrupt took 144523656 ns
[  558.262053] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/subdev/bar/g84.c:38/g84_bar_flush()!
[  558.262085] nouveau 0000:04:00.0: fifo: intr 00200000
[  559.852876] nouveau 0000:04:00.0: timeout at /home/pmoreau/projects/nouveau/nouveau/drm/nouveau/nvkm/subdev/bar/g84.c:38/g84_bar_flush()!
[  559.852907] nouveau 0000:04:00.0: fifo: intr 04800000
[  563.418621] INFO: NMI handler (ghes_notify_nmi) took too long to run: 48.172 msecs
[  563.563141] INFO: NMI handler (perf_event_nmi_handler) took too long to run: 48.172 msecs
[  563.659490] perf: interrupt took too long (376349 > 2500), lowering kernel.perf_event_max_sample_rate to 300
[  578.942906] NMI watchdog: Watchdog detected hard LOCKUP on cpu 0
[  578.942909] Modules linked in: netconsole snd_hda_codec_hdmi ipmi_ssif snd_hda_codec_realtek snd_hda_codec_generic i915 intel_gtt intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd nls_iso8859_1 nls_cp437 vfat fat i2c_i801 snd_hda_intel e1000e nouveau(O) igb snd_hda_codec ast ptp mxm_wmi pps_core snd_hda_core snd_hwdep ttm i2c_algo_bit dca snd_pcm drm_kms_helper snd_timer evdev syscopyarea joydev mousedev sysfillrect input_leds snd sysimgblt led_class fb_sys_fops soundcore mac_hid drm mei_me hci_uart mei ipmi_si btbcm shpchp wmi fan battery btqca thermal pinctrl_sunrisepoint ipmi_msghandler btintel video pinctrl_intel bluetooth intel_lpss_acpi intel_lpss crc16 i2c_hid tpm_tis acpi_pad button tpm processor fjes sch_fq_codel ip_tables x_tables btrfs xor hid_generic usbhid hid raid6_pq sd_mod crc32c_intel ahci libahci libata xhci_pci xhci_hcd scsi_mod usbcore usb_common
[  578.942996] CPU: 0 PID: 498 Comm: Xorg Tainted: G           O    4.6.0-rc2-NOUVEAU-189048-gf9fd2ad #5
[  578.942997] Hardware name: Supermicro Super Server/X11SAT-F, BIOS 1.0 02/05/2016
[  578.942999]  0000000000000086 00000000517a463a ffff8802c5805b50 ffffffff812da391
[  578.943002]  0000000000000000 0000000000000001 ffff8802c5805b68 ffffffff81116c3d
[  578.943005]  ffff8802bd015000 ffff8802c5805ba0 ffffffff8115f2ac 0000000000000001
[  578.943008] Call Trace:
[  578.943009]  <NMI>  [<ffffffff812da391>] dump_stack+0x63/0x82
[  578.943014]  [<ffffffff81116c3d>] watchdog_overflow_callback+0xbd/0xd0
[  578.943017]  [<ffffffff8115f2ac>] __perf_event_overflow+0x8c/0x1d0
[  578.943018]  [<ffffffff8115fe94>] perf_event_overflow+0x14/0x20
[  578.943020]  [<ffffffff8100c761>] intel_pmu_handle_irq+0x1e1/0x4a0
[  578.943023]  [<ffffffff811af4fd>] ? vunmap_page_range+0x20d/0x330
[  578.943025]  [<ffffffff811af631>] ? unmap_kernel_range_noflush+0x11/0x20
[  578.943027]  [<ffffffff8139006b>] ? ghes_copy_tofrom_phys+0x11b/0x2a0
[  578.943029]  [<ffffffff81050bff>] ? native_apic_wait_icr_idle+0x1f/0x30
[  578.943031]  [<ffffffff8100594d>] perf_event_nmi_handler+0x2d/0x50
[  578.943033]  [<ffffffff8103206e>] nmi_handle+0x5e/0x130
[  578.943035]  [<ffffffff81032680>] default_do_nmi+0xd0/0x120
[  578.943036]  [<ffffffff810327b2>] do_nmi+0xe2/0x130
[  578.943108]  [<ffffffff815adc71>] end_repeat_nmi+0x1a/0x1e
[  578.943111]  [<ffffffff812f30b0>] ? ioread32+0x30/0x40
[  578.943112]  [<ffffffff812f30b0>] ? ioread32+0x30/0x40
[  578.943113]  [<ffffffff812f30b0>] ? ioread32+0x30/0x40
[  578.943114]  <<EOE>>  [<ffffffffa0641f65>] ? nv04_timer_read+0x25/0x70 [nouveau]
[  578.943136]  [<ffffffffa0641adf>] nvkm_timer_read+0xf/0x20 [nouveau]
[  578.943143]  [<ffffffffa05ee6e2>] g84_bar_flush+0x62/0xe0 [nouveau]
[  578.943149]  [<ffffffffa05ede5c>] nvkm_bar_flush+0x1c/0x20 [nouveau]
[  578.943159]  [<ffffffffa06311bd>] nvkm_instobj_release+0x1d/0x20 [nouveau]
[  578.943169]  [<ffffffffa0637250>] nv50_vm_map_sg+0x100/0x110 [nouveau]
[  578.943178]  [<ffffffffa06355eb>] nvkm_vm_map+0x2fb/0x3b0 [nouveau]
[  578.943186]  [<ffffffffa0635d76>] ? nvkm_vm_get+0x1d6/0x260 [nouveau]
[  578.943195]  [<ffffffffa0692eee>] nouveau_bo_vma_add+0x5e/0x90 [nouveau]
[  578.943203]  [<ffffffffa069345a>] nouveau_gem_object_open+0x15a/0x190 [nouveau]
[  578.943207]  [<ffffffffa04000b9>] drm_gem_handle_create_tail+0xe9/0x1b0 [drm]
[  578.943210]  [<ffffffffa04001b5>] drm_gem_handle_create+0x35/0x40 [drm]
[  578.943218]  [<ffffffffa0693882>] nouveau_gem_ioctl_new+0xc2/0x1d0 [nouveau]
[  578.943221]  [<ffffffffa0400ae2>] drm_ioctl+0x152/0x540 [drm]
[  578.943229]  [<ffffffffa06937c0>] ? nouveau_gem_new+0x150/0x150 [nouveau]
[  578.943232]  [<ffffffff8117b826>] ? lru_cache_add_active_or_unevictable+0x36/0xb0
[  578.943240]  [<ffffffffa068c2c8>] nouveau_drm_ioctl+0x68/0xc0 [nouveau]
[  578.943242]  [<ffffffff812034f1>] do_vfs_ioctl+0xa1/0x5b0
[  578.943244]  [<ffffffff810637d4>] ? __do_page_fault+0x1f4/0x510
[  578.943245]  [<ffffffff81203a79>] SyS_ioctl+0x79/0x90
[  578.943247]  [<ffffffff815aba32>] entry_SYSCALL_64_fastpath+0x1a/0xa4

-- GitLab Migration Automatic Message --

This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity.

You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/xorg/driver/xf86-video-nouveau/issues/127.