Nouveau - Jan 2018 - Problems getting nouveau to work with either Geforce GT710 or Geforce 9800GT on ARM Cortex-A9

The Geforce 9800GT also shows different behaviour without nvidiafb in
the kernel. I get lengthy messages from nouveau, like the ones found
in the email attachment.
It also eventually gets into the lightdm login graphical screen, but
with this card the screen is highly distorted, despite the mouse
cursor being properly displayed and moving around properly.

Luis

On Thu, Jan 25, 2018 at 1:14 PM, Luís Mendes <luis.p.mendes at gmail.com>
wrote:
> Hi Ben,
>
> Ok! I've removed nvidiafb from kernel config.
>
> Now linux is able to boot with the msi Geforce GT 710 card and
> eventually ends up displaying a slightly distorted lightdm login
> screen. The mouse cursor moves, but never changes to typing cursor
> mode when hovering the password input box, and I am also unable to
> enter the password, some graphics glitches in the login box too. There
> are warnings about semaphores problems in the logs.
> I believe one difference between this Cortex-A9 and Tegra SoCs is that
> the Cortex-A9 has no IOMMU.
>
> In attachment follows the boot log and the dmesg kernel log.
>
> Luís
-------------- next part --------------
#DMESG excerpt

Jan 25 13:16:36 localhost kernel: [    6.279866] EXT4-fs (sda2): re-mounted.
Opts: errors=remount-ro
Jan 25 13:16:36 localhost kernel: [    7.669673] nouveau 0000:01:00.0: enabling
device (0140 -> 0143)
Jan 25 13:16:36 localhost kernel: [    7.669780] nouveau 0000:01:00.0: NVIDIA
G92 (092a00a2)
Jan 25 13:16:36 localhost kernel: [    7.781533] nouveau 0000:01:00.0: bios:
version 62.92.99.00.00
Jan 25 13:16:36 localhost kernel: [    7.951654] nouveau 0000:01:00.0: fb: 512
MiB GDDR3
Jan 25 13:16:36 localhost kernel: [    8.116784] [TTM] Zone  kernel: Available
graphics memory: 512056 kiB
Jan 25 13:16:36 localhost kernel: [    8.116788] [TTM] Initializing pool
allocator
Jan 25 13:16:36 localhost kernel: [    8.116797] [TTM] Initializing DMA pool
allocator
Jan 25 13:16:36 localhost kernel: [    8.116830] nouveau 0000:01:00.0: DRM:
VRAM: 512 MiB
Jan 25 13:16:36 localhost kernel: [    8.116833] nouveau 0000:01:00.0: DRM:
GART: 1048576 MiB
Jan 25 13:16:36 localhost kernel: [    8.116840] nouveau 0000:01:00.0: DRM: TMDS
table version 2.0
Jan 25 13:16:36 localhost kernel: [    8.116844] nouveau 0000:01:00.0: DRM: DCB
version 4.0
Jan 25 13:16:36 localhost kernel: [    8.116848] nouveau 0000:01:00.0: DRM: DCB
outp 01: 01011312 00020030
Jan 25 13:16:36 localhost kernel: [    8.116852] nouveau 0000:01:00.0: DRM: DCB
outp 03: 02022322 00020010
Jan 25 13:16:36 localhost kernel: [    8.116856] nouveau 0000:01:00.0: DRM: DCB
conn 00: 00000000
Jan 25 13:16:36 localhost kernel: [    8.116859] nouveau 0000:01:00.0: DRM: DCB
conn 01: 00001130
Jan 25 13:16:36 localhost kernel: [    8.116862] nouveau 0000:01:00.0: DRM: DCB
conn 02: 00002261
Jan 25 13:16:36 localhost kernel: [    8.158366] [drm] Supports vblank timestamp
caching Rev 2 (21.10.2013).
Jan 25 13:16:36 localhost kernel: [    8.158370] [drm] Driver supports precise
vblank timestamp query.
Jan 25 13:16:36 localhost kernel: [    8.192217] nouveau 0000:01:00.0: DRM: MM:
using CRYPT for buffer copies
Jan 25 13:16:36 localhost kernel: [    8.272983] nouveau 0000:01:00.0: DRM:
allocated 1920x1080 fb: 0x70000, bo 9b5dc40b
Jan 25 13:16:36 localhost kernel: [    8.456001] Console: switching to colour
frame buffer device 240x67
Jan 25 13:16:36 localhost kernel: [    8.472590] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000004 ib_put
00000005 state a0006610 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472641] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000005 ib_put
00000006 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472687] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000006 ib_put
00000007 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472732] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000007 ib_put
00000008 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472777] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000008 ib_put
00000009 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472822] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000009 ib_put
0000000a state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472867] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000a ib_put
0000000b state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472912] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000b ib_put
0000000c state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.472957] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000c ib_put
0000000d state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473002] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000d ib_put
0000000e state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473047] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000e ib_put
0000000f state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473092] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 0000000f ib_put
00000010 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473137] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000010 ib_put
00000011 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473182] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000011 ib_put
00000012 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473227] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000012 ib_put
00000013 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473273] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000013 ib_put
00000014 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.473307] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 1 [DRM] get 0000001200 put 0000001200 ib_get 00000014 ib_put
00000015 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:36 localhost kernel: [    8.647161] nouveau 0000:01:00.0: DRM: GPU
lockup - switching to software fbcon
Jan 25 13:16:36 localhost kernel: [    9.098503] nouveau 0000:01:00.0: fb0:
nouveaufb frame buffer device
Jan 25 13:16:36 localhost kernel: [    9.361305] [drm] Initialized nouveau 1.3.1
20120801 for 0000:01:00.0 on minor 0
Jan 25 13:16:36 localhost kernel: [    9.658257] Adding 1952764k swap on
/dev/sda1.  Priority:-2 extents:1 across:1952764k SS
Jan 25 13:16:36 localhost kernel: [   10.274184] EXT4-fs (mmcblk0p1): mounted
filesystem with ordered data mode. Opts: errors=remount-ro
Jan 25 13:16:38 localhost kernel: [   13.686118] IPv6: ADDRCONF(NETDEV_UP):
eth0: link is not ready
Jan 25 13:16:39 localhost kernel: [   13.783210] IPv6: ADDRCONF(NETDEV_UP):
eth0: link is not ready
Jan 25 13:16:39 localhost kernel: [   13.801772] IPv6: ADDRCONF(NETDEV_UP):
eth1: link is not ready
Jan 25 13:16:39 localhost kernel: [   13.802572] IPv6: ADDRCONF(NETDEV_UP):
eth1: link is not ready
Jan 25 13:16:39 localhost kernel: [   13.825665] IPv6: ADDRCONF(NETDEV_UP):
eth2: link is not ready
Jan 25 13:16:39 localhost kernel: [   13.826395] IPv6: ADDRCONF(NETDEV_UP):
eth2: link is not ready
Jan 25 13:16:40 localhost kernel: [   14.872400] mvneta f1034000.ethernet eth2:
Link is Up - 1Gbps/Full - flow control off
Jan 25 13:16:40 localhost kernel: [   14.872414] mvneta f1030000.ethernet eth1:
Link is Up - 1Gbps/Full - flow control off
Jan 25 13:16:40 localhost kernel: [   14.872427] IPv6: ADDRCONF(NETDEV_CHANGE):
eth2: link becomes ready
Jan 25 13:16:40 localhost kernel: [   14.872519] IPv6: ADDRCONF(NETDEV_CHANGE):
eth1: link becomes ready
Jan 25 13:16:40 localhost kernel: [   15.613113] fuse init (API version 7.26)
Jan 25 13:16:40 localhost kernel: [   15.645347] random: crng init done
Jan 25 13:16:41 localhost kernel: [   16.153852] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 4 [Xorg[3049]] get 0000000000 put 0000000000 ib_get 00000000
ib_put 00000002 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:42 localhost kernel: [   17.042696] mvneta f1070000.ethernet eth0:
Link is Up - 1Gbps/Full - flow control rx/tx
Jan 25 13:16:42 localhost kernel: [   17.042711] IPv6: ADDRCONF(NETDEV_CHANGE):
eth0: link becomes ready
Jan 25 13:16:56 localhost kernel: [   31.242325] nouveau 0000:01:00.0:
Xorg[3049]: failed to idle channel 4 [Xorg[3049]]
Jan 25 13:16:57 localhost kernel: [   32.623542] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009140 put 0000009140 ib_get 0000000c
ib_put 0000000e state a0000024 (err: IB_EMPTY) push 00406040
Jan 25 13:16:57 localhost kernel: [   32.642976] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009180 put 0000009180 ib_get 00000010
ib_put 00000014 state a0000024 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   32.704030] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009200 put 0000009200 ib_get 00000018
ib_put 0000001a state a0000024 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   32.719945] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009200 put 0000009200 ib_get 0000001a
ib_put 00000020 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   32.770249] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 00000092c0 put 00000092c0 ib_get 00000024
ib_put 00000026 state a0000024 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   32.817486] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 00000092c0 put 00000092c0 ib_get 00000026
ib_put 00000028 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   32.833384] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 00000092c0 put 00000092c0 ib_get 00000028
ib_put 00000030 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.227056] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009580 put 0000009580 ib_get 00000050
ib_put 00000052 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.243013] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009580 put 0000009580 ib_get 00000052
ib_put 00000054 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.259162] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009580 put 0000009580 ib_get 00000054
ib_put 00000056 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.275680] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 00000058
ib_put 0000005a state a0000024 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.291709] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 0000005a
ib_put 0000005c state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.307730] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 0000005c
ib_put 0000005e state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.324016] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 0000005e
ib_put 00000060 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.339989] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 00000060
ib_put 00000062 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.355972] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 00000062
ib_put 00000064 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.372025] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009600 put 0000009600 ib_get 00000064
ib_put 00000066 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:16:58 localhost kernel: [   33.388201] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009700 put 0000009700 ib_get 00000068
ib_put 0000006a state a0000024 (err: IB_EMPTY) push 00406040

### and finally stops after a while with

Jan 25 13:17:27 localhost kernel: [   62.796636] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000000d040 put 000000d040 ib_get 000003fe
ib_put 00000000 state a0000000 (err: IB_EMPTY) push 00406040
Jan 25 13:17:28 localhost kernel: [   63.798295] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 0 - 00000040 [RT_FAULT] - Address 00c0138000
Jan 25 13:17:28 localhost kernel: [   63.807196] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 0 - e0c: 00000000, e18: 00000000, e1c: 00020000, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.818969] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 2 - 00000040 [RT_FAULT] - Address 00c0138400
Jan 25 13:17:28 localhost kernel: [   63.827863] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 2 - e0c: 00000000, e18: 00000000, e1c: 00020010, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.839635] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 3 - 00000040 [RT_FAULT] - Address 00c0138800
Jan 25 13:17:28 localhost kernel: [   63.848528] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 3 - e0c: 00000000, e18: 00000000, e1c: 00020020, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.860298] nouveau 0000:01:00.0: gr:
00200000 [] ch 2 [001f9f5000 Xorg[3049]] subc 7 class 8297 mthd 15e0 data
00000000
Jan 25 13:17:28 localhost kernel: [   63.871209] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 00000090d4 put 00000090e0 ib_get 00000006
ib_put 00000006 state 80000000 (err: INVALID_CMD) push 00406040
Jan 25 13:17:28 localhost kernel: [   63.887345] nouveau 0000:01:00.0: fb:
trapped write at 00c0138000 on channel 2 [1f9f5000 Xorg[3049]] engine 00
[PGRAPH] client 0b [PROP] subclient 00 [RT0] reason 00000002 [PAGE_NOT_PRESENT]
Jan 25 13:17:28 localhost kernel: [   63.904376] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 0 - 00000040 [RT_FAULT] - Address 00c0138000
Jan 25 13:17:28 localhost kernel: [   63.913271] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 0 - e0c: 00000000, e18: 00000000, e1c: 00020000, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.925044] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 2 - 00000040 [RT_FAULT] - Address 00c0138400
Jan 25 13:17:28 localhost kernel: [   63.933936] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 2 - e0c: 00000000, e18: 00000000, e1c: 00020010, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.945708] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 3 - 00000040 [RT_FAULT] - Address 00c0138800
Jan 25 13:17:28 localhost kernel: [   63.954600] nouveau 0000:01:00.0: gr:
TRAP_PROP - TP 3 - e0c: 00000000, e18: 00000000, e1c: 00020020, e20: 00002a00,
e24: 00030000
Jan 25 13:17:28 localhost kernel: [   63.966370] nouveau 0000:01:00.0: gr:
00200000 [] ch 2 [001f9f5000 Xorg[3049]] subc 7 class 8297 mthd 15e0 data
00000000
Jan 25 13:17:28 localhost kernel: [   63.977277] nouveau 0000:01:00.0: fb:
trapped read at 00c0138700 on channel 2 [1f9f5000 Xorg[3049]] engine 00 [PGRAPH]
client 0a [TEXTURE] subclient 00 [] reason 00000002 [PAGE_NOT_PRESENT]
Jan 25 13:17:28 localhost kernel: [   63.994206] nouveau 0000:01:00.0: gr: magic
set 0:
Jan 25 13:17:28 localhost kernel: [   63.999012] nouveau 0000:01:00.0: gr: 
00408904: 20087c02
Jan 25 13:17:28 localhost kernel: [   64.004422] nouveau 0000:01:00.0: gr: 
00408908: 00c01387
Jan 25 13:17:28 localhost kernel: [   64.009834] nouveau 0000:01:00.0: gr: 
0040890c: 80000430
Jan 25 13:17:28 localhost kernel: [   64.015244] nouveau 0000:01:00.0: gr: 
00408910: 13800000
Jan 25 13:17:28 localhost kernel: [   64.020655] nouveau 0000:01:00.0: gr:
TRAP_TEXTURE - TP0: 00000003 [ FAULT]
Jan 25 13:17:28 localhost kernel: [   64.027639] nouveau 0000:01:00.0: gr:
00200000 [] ch 2 [001f9f5000 Xorg[3049]] subc 2 class 502d mthd 024c data
00000438
Jan 25 13:17:28 localhost kernel: [   64.038561] nouveau 0000:01:00.0: gr:
DATA_ERROR 00000004 [INVALID_VALUE]
Jan 25 13:17:28 localhost kernel: [   64.045368] nouveau 0000:01:00.0: gr:
00100000 [] ch 2 [001f9f5000 Xorg[3049]] subc 2 class 502d mthd 0284 data
00040060
Jan 25 13:17:28 localhost kernel: [   64.056286] nouveau 0000:01:00.0: gr:
DATA_ERROR 00000004 [INVALID_VALUE]
Jan 25 13:17:28 localhost kernel: [   64.063092] nouveau 0000:01:00.0: gr:
00100000 [] ch 2 [001f9f5000 Xorg[3049]] subc 2 class 502d mthd 0288 data
beef0201
Jan 25 13:17:28 localhost kernel: [   64.074007] nouveau 0000:01:00.0: gr:
DATA_ERROR 00000004 [INVALID_VALUE]
Jan 25 13:17:28 localhost kernel: [   64.080812] nouveau 0000:01:00.0: gr:
00100000 [] ch 2 [001f9f5000 Xorg[3049]] subc 2 class 502d mthd 028c data
00140010
Jan 25 13:17:29 localhost kernel: [   64.799222] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000001e10c put 000001eb60 ib_get 00000009
ib_put 0000000a state 80000024 (err: INVALID_CMD) push 00406040
Jan 25 13:17:29 localhost kernel: [   64.816082] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000001eb60 put 000001ebcc ib_get 0000000b
ib_put 0000000c state 80000024 (err: INVALID_CMD) push 00406040
Jan 25 13:17:35 localhost kernel: [   70.806415] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000026270 put 0000028ab4 ib_get 00000021
ib_put 00000022 state 80000000 (err: INVALID_CMD) push 00406040
Jan 25 13:17:35 localhost kernel: [   70.823485] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000002b444 put 000002b5cc ib_get 00000025
ib_put 00000026 state 40000004 (err: INVALID_MTHD) push 00406040
Jan 25 13:17:36 localhost kernel: [   71.807770] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000002b63c put 000002cde0 ib_get 00000029
ib_put 0000002a state 80000000 (err: INVALID_CMD) push 00406040
Jan 25 13:17:37 localhost kernel: [   72.809041] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000002d22c put 000002d2e4 ib_get 0000002d
ib_put 0000002e state 80000024 (err: INVALID_CMD) push 00406040
Jan 25 13:17:40 localhost kernel: [   75.812789] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009418 put 0000009420 ib_get 0000003a
ib_put 0000003a state 8000ef05 (err: INVALID_CMD) push 00406040
Jan 25 13:17:41 localhost kernel: [   76.814010] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000034e74 put 0000034fd0 ib_get 0000003f
ib_put 00000040 state 80000000 (err: INVALID_CMD) push 00406040
Jan 25 13:17:43 localhost kernel: [   78.816168] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 000003a080 put 000003a98c ib_get 00000045
ib_put 00000046 state 80000000 (err: INVALID_CMD) push 00406040
Jan 25 13:17:44 localhost kernel: [   79.817313] nouveau 0000:01:00.0: fifo:
DMA_PUSHER - ch 2 [Xorg[3049]] get 0000009504 put 0000009520 ib_get 0000004a
ib_put 0000004a state 8000e6a8 (err: INVALID_CMD) push 00406040

Sorry for alI these individual emails, but I think is more readable
this way, by having each independent information in a separate email.

I also have these additional cards that I can try: Gefoce GT 1030 and
Geforce GTX 1050 Ti.

I have tried Geforce GT 1030 and it is working nicely but without
hardware acceleration. Without the hardware acceleration I am able to
get into X (Ubuntu Mate 17.10) and run glmark2.
dmesg follows in attachment.

I believe this indicates there is no issue with cards initialization,
but is rather something to do with the hardware acceleration code.

Luís

On Thu, Jan 25, 2018 at 1:27 PM, Luís Mendes <luis.p.mendes at gmail.com>
wrote:
> The Geforce 9800GT also shows different behaviour without nvidiafb in
> the kernel. I get lengthy messages from nouveau, like the ones found
> in the email attachment.
> It also eventually gets into the lightdm login graphical screen, but
> with this card the screen is highly distorted, despite the mouse
> cursor being properly displayed and moving around properly.
>
> Luis
>
> On Thu, Jan 25, 2018 at 1:14 PM, Luís Mendes <luis.p.mendes at
gmail.com> wrote:
>> Hi Ben,
>>
>> Ok! I've removed nvidiafb from kernel config.
>>
>> Now linux is able to boot with the msi Geforce GT 710 card and
>> eventually ends up displaying a slightly distorted lightdm login
>> screen. The mouse cursor moves, but never changes to typing cursor
>> mode when hovering the password input box, and I am also unable to
>> enter the password, some graphics glitches in the login box too. There
>> are warnings about semaphores problems in the logs.
>> I believe one difference between this Cortex-A9 and Tegra SoCs is that
>> the Cortex-A9 has no IOMMU.
>>
>> In attachment follows the boot log and the dmesg kernel log.
>>
>> Luís
-------------- next part --------------
ubuntu at linux:~$ dmesg
[    4.581738] ata3: SATA link down (SStatus 0 SControl 300)
[    4.595035] usb usb5: We don't know the algorithms for LPM for this host,
disabling LPM.
[    4.603381] hub 5-0:1.0: USB hub found
[    4.607153] hub 5-0:1.0: 1 port detected
[    4.611310] usbcore: registered new interface driver usb-storage
[    4.617437] mousedev: PS/2 mouse device common for all mice
[    4.623515] armada38x-rtc f10a3800.rtc: rtc core: registered f10a3800.rtc as
rtc0
[    4.631161] i2c /dev entries driver
[    4.634939] pca953x 0-0020: 0-0020 supply vcc not found, using dummy
regulator
[    4.651066] GPIO line 496 (pcie1.0-clkreq) hogged as input
[    4.657605] GPIO line 499 (pcie1.0-w-disable) hogged as output/low
[    4.664526] GPIO line 501 (usb3-current-limit) hogged as input
[    4.671400] GPIO line 502 (usb3-power) hogged as output/high
[    4.678107] GPIO line 507 (m.2 devslp) hogged as output/low
[    4.684417] GPIO line 508 (sfp-los) hogged as input
[    4.690022] GPIO line 509 (sfp-tx-fault) hogged as input
[    4.696380] GPIO line 510 (sfp-tx-disable) hogged as output/low
[    4.703036] GPIO line 511 (sfp-mod-def0) hogged as input
[    4.709077] GPIO line 500 (pcie2.0-clkreq) hogged as input
[    4.714593] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    4.715619] GPIO line 503 (pcie2.0-w-disable) hogged as output/low
[    4.727055] pca953x 0-0020: interrupt support not compiled in
[    4.732871] ata1.00: supports DRM functions and may not be fully accessible
[    4.733201] IR NEC protocol handler initialized
[    4.733203] IR RC5(x/sz) protocol handler initialized
[    4.733204] IR RC6 protocol handler initialized
[    4.733205] IR JVC protocol handler initialized
[    4.733206] IR Sony protocol handler initialized
[    4.733207] IR SANYO protocol handler initialized
[    4.733208] IR Sharp protocol handler initialized
[    4.733209] IR MCE Keyboard/mouse protocol handler initialized
[    4.733210] IR XMP protocol handler initialized
[    4.743415] (NULL device *): hwmon_device_register() is deprecated. Please
convert the driver to use hwmon_device_register_with_info().
[    4.743646] orion_wdt: Initial timeout 171 sec
[    4.743915] sdhci: Secure Digital Host Controller Interface driver
[    4.743916] sdhci: Copyright(c) Pierre Ossman
[    4.744070] sdhci-pxav3 f10d8000.sdhci: Got CD GPIO
[    4.801715] mmc0: SDHCI controller on f10d8000.sdhci [f10d8000.sdhci] using
ADMA
[    4.801818] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.802004] usbcore: registered new interface driver usbhid
[    4.802005] usbhid: USB HID core driver
[    4.802965] NET: Registered protocol family 10
[    4.803970] Segment Routing with IPv6
[    4.804003] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    4.804236] NET: Registered protocol family 17
[    4.807621] 8021q: 802.1Q VLAN Support v1.8
[    4.807695] ThumbEE CPU extension supported.
[    4.807699] Registering SWP/SWPB emulation handler
[    4.807967] Loading compiled-in X.509 certificates
[    4.808742] Btrfs loaded, crc32c=crc32c-generic
[    4.809731] mvebu-pcie soc:pcie: /soc/pcie/pcie at 2,0: reset gpio is active
low
[    4.809763] mvebu-pcie soc:pcie: /soc/pcie/pcie at 3,0: reset gpio is active
low
[    4.855040] mmc0: new high speed SDHC card at address aaaa
[    4.855241] mmcblk0: mmc0:aaaa SL16G 14.8 GiB 
[    4.857099]  mmcblk0: p1 p2
[    4.905724] ata1.00: ATA-9: Samsung SSD 850 EVO mSATA 250GB, EMT41B6Q, max
UDMA/133
[    4.913401] ata1.00: 488397168 sectors, multi 1: LBA48 NCQ (depth 31/32)
[    4.923153] ata1.00: supports DRM functions and may not be fully accessible
[    4.931515] ata1.00: configured for UDMA/133
[    4.935970] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 850  1B6Q
PQ: 0 ANSI: 5
[    4.944447] sd 0:0:0:0: [sda] 488397168 512-byte logical blocks: (250 GB/233
GiB)
[    4.951993] sd 0:0:0:0: [sda] Write Protect is off
[    4.956796] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    4.956845] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[    4.966623]  sda: sda1 sda2 sda3
[    4.970425] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    5.041726] mvebu-pcie soc:pcie: PCI host bridge to bus 0000:00
[    5.047662] pci_bus 0000:00: root bus resource [io  0x1000-0xfffff]
[    5.053954] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xefffffff]
[    5.060845] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.066361] pci 0000:00:02.0: [11ab:6828] type 01 class 0x060400
[    5.066459] pci 0000:00:03.0: [11ab:6828] type 01 class 0x060400
[    5.066542] PCI: bus0: Fast back to back transfers disabled
[    5.071642] usb 4-1: new high-speed USB device number 2 using xhci-hcd
[    5.078696] pci 0000:00:02.0: bridge configuration invalid ([bus 00-00]),
reconfiguring
[    5.086726] pci 0000:00:03.0: bridge configuration invalid ([bus 00-00]),
reconfiguring
[    5.094803] pci 0000:01:00.0: [10de:1d01] type 00 class 0x030000
[    5.094834] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00ffffff]
[    5.094846] pci 0000:01:00.0: reg 0x14: [mem 0x00000000-0x0fffffff 64bit
pref]
[    5.094857] pci 0000:01:00.0: reg 0x1c: [mem 0x00000000-0x01ffffff 64bit
pref]
[    5.094865] pci 0000:01:00.0: reg 0x24: [io  0x0000-0x007f]
[    5.094873] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0007ffff pref]
[    5.095001] pci 0000:01:00.0: vgaarb: VGA device added:
decodes=io+mem,owns=none,locks=none
[    5.103411] pci 0000:01:00.1: [10de:0fb8] type 00 class 0x040300
[    5.103432] pci 0000:01:00.1: reg 0x10: [mem 0x00000000-0x00003fff]
[    5.103588] PCI: bus1: Fast back to back transfers disabled
[    5.109176] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    5.109212] PCI: bus2: Fast back to back transfers enabled
[    5.114719] pci_bus 0000:02: busn_res: [bus 02-ff] end is updated to 02
[    5.114737] pci 0000:00:02.0: BAR 8: assigned [mem 0xc0000000-0xd7ffffff]
[    5.121541] pci 0000:00:02.0: BAR 7: assigned [io  0x10000-0x10fff]
[    5.127832] pci 0000:01:00.0: BAR 1: assigned [mem 0xc0000000-0xcfffffff
64bit pref]
[    5.135611] pci 0000:01:00.0: BAR 3: assigned [mem 0xd0000000-0xd1ffffff
64bit pref]
[    5.143382] pci 0000:01:00.0: BAR 0: assigned [mem 0xd2000000-0xd2ffffff]
[    5.150188] pci 0000:01:00.0: BAR 6: assigned [mem 0xd3000000-0xd307ffff
pref]
[    5.157432] pci 0000:01:00.1: BAR 0: assigned [mem 0xd3080000-0xd3083fff]
[    5.164241] pci 0000:01:00.0: BAR 5: assigned [io  0x10000-0x1007f]
[    5.170525] pci 0000:00:02.0: PCI bridge to [bus 01]
[    5.175507] pci 0000:00:02.0:   bridge window [io  0x10000-0x10fff]
[    5.181795] pci 0000:00:02.0:   bridge window [mem 0xc0000000-0xd7ffffff]
[    5.188600] pci 0000:00:03.0: PCI bridge to [bus 02]
[    5.193623] pcieport 0000:00:02.0: enabling device (0140 -> 0143)
[    5.200278] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    5.207184] armada38x-rtc f10a3800.rtc: setting system clock to 2018-01-25
13:33:13 UTC (1516887193)
[    5.216426] cfg80211: Loading compiled-in X.509 certificates for regulatory
database
[    5.225694] cfg80211: Loaded X.509 cert 'sforshee:
00b28ddf47aef9cea7'
[    5.232279] ALSA device list:
[    5.235257]   No soundcards found.
[    5.238895] platform regulatory.0: Direct firmware load for regulatory.db
failed with error -2
[    5.247453] cfg80211: failed to load regulatory.db
[    5.247592] md: Waiting for all devices to be available before autodetect
[    5.254418] md: If you don't use raid, use raid=noautodetect
[    5.260317] md: Autodetecting RAID arrays.
[    5.264436] md: autorun ...
[    5.267233] md: ... autorun DONE.
[    5.271058] hub 4-1:1.0: USB hub found
[    5.274882] hub 4-1:1.0: 4 ports detected
[    5.277856] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:
(null)
[    5.277875] VFS: Mounted root (ext4 filesystem) on device 8:2.
[    5.278931] devtmpfs: mounted
[    5.295986] Freeing unused kernel memory: 1024K
[    5.365662] systemd[1]: systemd 234 running in system mode. (+PAM +AUDIT
+SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS
+ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN
default-hierarchy=hybrid)
[    5.386578] systemd[1]: Detected architecture arm.
[    5.421813] systemd[1]: Set hostname to <localhost.localdomain>.
[    5.581432] systemd[1]: Listening on Syslog Socket.
[    5.611657] usb 4-1.2: new low-speed USB device number 3 using xhci-hcd
[    5.611818] systemd[1]: Listening on Journal Socket.
[    5.651714] systemd[1]: Reached target System Time Synchronized.
[    5.691868] systemd[1]: Created slice User and Session Slice.
[    5.741747] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    5.781718] systemd[1]: Listening on Journal Socket (/dev/log).
[    5.795850] input: Trust Trust Wireless TouchKB as
/devices/platform/soc/soc:internal-regs/f10f8000.usb3/usb4/4-1/4-1.2/4-1.2:1.0/0003:145F:01D3.0001/input/input1
[    5.821814] systemd[1]: Started Forward Password Requests to Wall Directory
Watch.
[    5.871869] hid-generic 0003:145F:01D3.0001: input: USB HID v1.10 Keyboard
[Trust Trust Wireless TouchKB] on usb-f10f8000.usb3-1.2/input0
[    5.891258] input: Trust Trust Wireless TouchKB as
/devices/platform/soc/soc:internal-regs/f10f8000.usb3/usb4/4-1/4-1.2/4-1.2:1.1/0003:145F:01D3.0002/input/input2
[    5.981788] hid-generic 0003:145F:01D3.0002: input: USB HID v1.10 Mouse
[Trust Trust Wireless TouchKB] on usb-f10f8000.usb3-1.2/input1
[    6.108478] lp: driver loaded but no devices found
[    6.116075] ppdev: user-space parallel port driver
[    6.443752] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro
[    7.089042] systemd-journald[1570]: Received request to flush runtime journal
from PID 1
[    7.567294] snd_hda_intel 0000:01:00.1: enabling device (0140 -> 0142)
[    7.567307] snd_hda_intel 0000:01:00.1: Disabling MSI
[    7.567311] snd_hda_intel 0000:01:00.1: Force to snoop mode by module option
[    7.659688] nouveau 0000:01:00.0: enabling device (0140 -> 0143)
[    7.659795] nouveau 0000:01:00.0: NVIDIA GP108 (138000a1)
[    7.795163] nouveau 0000:01:00.0: bios: version 86.08.0c.00.1a
[    7.875167] nouveau 0000:01:00.0: fb: 2048 MiB GDDR5
[    9.004757] Adding 1952764k swap on /dev/sda1.  Priority:-2 extents:1
across:1952764k SS
[    9.036117] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode.
Opts: errors=remount-ro
[    9.903056] input: HDA NVidia HDMI/DP,pcm=3 as
/devices/platform/soc/soc:pcie/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card0/input3
[    9.903932] input: HDA NVidia HDMI/DP,pcm=7 as
/devices/platform/soc/soc:pcie/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card0/input4
[   11.463706] [TTM] Zone  kernel: Available graphics memory: 512056 kiB
[   11.463712] [TTM] Initializing pool allocator
[   11.463723] [TTM] Initializing DMA pool allocator
[   11.463747] nouveau 0000:01:00.0: DRM: VRAM: 2048 MiB
[   11.463751] nouveau 0000:01:00.0: DRM: GART: 536870912 MiB
[   11.463757] nouveau 0000:01:00.0: DRM: BIT table 'A' not found
[   11.463761] nouveau 0000:01:00.0: DRM: BIT table 'L' not found
[   11.463765] nouveau 0000:01:00.0: DRM: TMDS table version 2.0
[   11.463768] nouveau 0000:01:00.0: DRM: DCB version 4.1
[   11.463773] nouveau 0000:01:00.0: DRM: DCB outp 00: 01000342 04620010
[   11.463777] nouveau 0000:01:00.0: DRM: DCB outp 01: 01011352 04620020
[   11.463781] nouveau 0000:01:00.0: DRM: DCB conn 00: 00001031
[   11.463785] nouveau 0000:01:00.0: DRM: DCB conn 01: 00002161
[   11.465645] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   11.465648] [drm] Driver supports precise vblank timestamp query.
[   11.467174] nouveau 0000:01:00.0: DRM: failed to create kernel channel, -22
[   11.782953] nouveau 0000:01:00.0: DRM: allocated 1920x1080 fb: 0x200000, bo
9de54446
[   11.893865] Console: switching to colour frame buffer device 240x67
[   12.114354] nouveau 0000:01:00.0: fb0: nouveaufb frame buffer device
[   12.341697] [drm] Initialized nouveau 1.3.1 20120801 for 0000:01:00.0 on
minor 0
[   13.382580] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.554107] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.569792] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   13.570666] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   13.586227] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
[   13.587120] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
[   14.631666] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow
control off
[   14.631682] mvneta f1030000.ethernet eth1: Link is Up - 1Gbps/Full - flow
control off
[   14.631697] IPv6: ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
[   14.631799] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[   15.260026] random: crng init done
[   15.408152] fuse init (API version 7.26)
[   16.791999] mvneta f1070000.ethernet eth0: Link is Up - 1Gbps/Full - flow
control rx/tx
[   16.792014] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

On Thu, Jan 25, 2018 at 8:47 AM, Luís Mendes <luis.p.mendes at gmail.com>
wrote:
> Sorry for alI these individual emails, but I think is more readable
> this way, by having each independent information in a separate email.
>
> I also have these additional cards that I can try: Gefoce GT 1030 and
> Geforce GTX 1050 Ti.
>
> I have tried Geforce GT 1030 and it is working nicely but without
> hardware acceleration. Without the hardware acceleration I am able to
> get into X (Ubuntu Mate 17.10) and run glmark2.
> dmesg follows in attachment.
This is a GP108 for which you need not-yet-upstream code to get acceleration.
>
> I believe this indicates there is no issue with cards initialization,
> but is rather something to do with the hardware acceleration code.
Someone associated with (or working at?) RH was investigating this for
some server-type ARM board with PCIe slots. (Was it you? Rob Clark,
CC'd, will remember who it was, and may be able to point you in an
appropriate direction.) I think that there were various odd issues
with coherency which remained only lightly diagnosed. Note that all
your boots instantaneously print "GPU lockup, have a nice day".
Everything after that is largely irrelevant.

This happens when it tries to execute some very basic things and sees
that there is no progress. This means sticking a command buffer on the
DMA ring, letting the GPU execute it, and making sure that the fence
is hit as seen by the CPU. I would recommend tracking down why the
accel doesn't work (i.e. you get that message), and not worry about
little details like "image appears on screen".

Unfortunately the initialization logic in nouveau is a giant pile of
spaghetti (with various arguably good reasons, but it doesn't change
the end result), so tracking down exactly what fails in the GPU init
will be a bit of an undertaking. You should join #nouveau on
irc.freenode.net and get some additional advice there.

Good luck,

  -ilia