Time-out in `nvkm_fifo_chan_child_fini()`

Submitted by Paul Menzel

Assigned to Nouveau Project

Link to original bug (#106080)

Description

Created attachment 138869
Linux 4.14.30 messages

With Linux 4.14.30, X.Org X Server 1.19.3, libdrm 2.4.89 and Nouveau 1.0.15, the X session of a user was just black coming back to the workplace on Monday.

Apr 14 02:19:06 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: gr: TRAP DISPATCH_QUERY<br>
Apr 14 02:19:06 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: gr: no stuck command?<br>
Apr 14 02:19:06 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: fb: trapped write at 0020215000 on channel 9 [1f53d000 timetunnel[22607]] engine 00 [PGRAPH] client 03 [DISPATCH] subclient 02 [QUERY] reason 00000002 [PAGE_NOT_PRESENT]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: timeout<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: ------------[ cut here ]------------<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: WARNING: CPU: 7 PID: 22608 at drivers/gpu/drm/nouveau/nvkm/engine/fifo/chang84.c:111 g84_fifo_chan_engine_fini+0x7b/0x2d0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: Modules linked in: fuse nfsv4 nfs nouveau 8021q wmi ttm drm_kms_helper garp mrp cfbfillrect syscopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops cfbcopyarea fb stp llc font fbdev drm x86_pkg_temp_thermal kvm_intel kvm irqbypass crc32c_intel video nfsd auth_rpcgss oid_registry nfs_acl lockd grace sunrpc sch_fq_codel ip_tables unix ipv6 autofs4<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: CPU: 7 PID: 22608 Comm: disk_cache:0 Not tainted 4.14.30.mx64.211 #1<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: Hardware name: Dell Inc. OptiPlex 7010/0KRC95, BIOS A13 03/25/2013<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: task: ffff8802092511c0 task.stack: ffffc9000356c000<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RIP: 0010:g84_fifo_chan_engine_fini+0x7b/0x2d0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RSP: 0018:ffffc9000356fa20 EFLAGS: 00010282<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RAX: 000000000000001d RBX: ffff880213e77460 RCX: 0000000000000000<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RDX: ffff88021dddbd70 RSI: ffff88021ddd53d8 RDI: ffff88021ddd53d8<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RBP: ffff880212c29c00 R08: 000000000000738b R09: 0000000000000001<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: R10: ffffffff8255ba20 R11: 0000000000000001 R12: ffff880131f6a808<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: R13: 0000000000000020 R14: ffff8801efc57000 R15: 0002a643d555de80<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: FS:  00007f8807e5c700(0000) GS:ffff88021ddc0000(0000) knlGS:0000000000000000<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: CR2: 00007faa51935a68 CR3: 000000000240a006 CR4: 00000000001606e0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: Call Trace:<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_fifo_chan_child_fini+0x63/0xf0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_oproxy_fini+0x2b/0x80 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_object_fini+0xb8/0x210 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_object_fini+0x73/0x210 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_ioctl_del+0x7e/0xa0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvkm_ioctl+0x10a/0x240 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nvif_object_fini+0x49/0x60 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nouveau_channel_del+0x6e/0x110 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nouveau_abi16_chan_fini.isra.0+0xa1/0xe0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nouveau_abi16_fini+0x2d/0x70 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  nouveau_drm_postclose+0x4b/0xd0 [nouveau]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  drm_release+0x264/0x380 [drm]<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  __fput+0xa6/0x1e0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  task_work_run+0x7e/0xa0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  do_exit+0x2bc/0xb20<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  do_group_exit+0x33/0xa0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  get_signal+0x1e4/0x570<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  do_signal+0x23/0x5c0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  ? wake_up_q+0x54/0x80<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  ? SyS_futex+0x11d/0x150<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  exit_to_usermode_loop+0x79/0x90<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  do_syscall_64+0xf0/0x110<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel:  entry_SYSCALL_64_after_hwframe+0x3d/0xa2<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RIP: 0033:0x7f8811ebc542<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RSP: 002b:00007f8807e5bd40 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RAX: fffffffffffffe00 RBX: 000000000074e878 RCX: 00007f8811ebc542<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RDX: 0000000000000000 RSI: 0000000000000080 RDI: 000000000074e8a0<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: RBP: 000000000074e8a0 R08: 0000000000000000 R09: 000000000000000c<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: R13: 00007f8807e5bd80 R14: 0000000000000000 R15: 000000000074e850<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: Code: 7d a0 e1 48 8b 7d 10 48 8b 5f 50 48 85 db 0f 84 5d 02 00 00 e8 67 aa 0d e1 48 89 da 48 89 c6 48 c7 c7 b8 1a 50 a0 e8 f0 7c ca e0 <0f> 0b 48 8b 75 78 8b 7c 24 08 48 81 c6 20 25 00 00 e8 4f f7 ff <br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: ---[ end trace 4c650f0757036e91 ]---<br>
Apr 14 02:19:08 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: fifo: channel 9 [timetunnel[22607]] unload timeout<br>
Apr 14 02:19:23 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: lament[22606]: failed to idle channel 8 [lament[22606]]<br>
Apr 14 02:19:38 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: lament[22606]: failed to idle channel 8 [lament[22606]]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: nouveau 0000:01:00.0: timeout<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: ------------[ cut here ]------------<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: WARNING: CPU: 5 PID: 22606 at drivers/gpu/drm/nouveau/nvkm/engine/fifo/chang84.c:111 g84_fifo_chan_engine_fini+0x7b/0x2d0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: Modules linked in: fuse nfsv4 nfs nouveau 8021q wmi ttm drm_kms_helper garp mrp cfbfillrect syscopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops cfbcopyarea fb stp llc font fbdev drm x86_pkg_temp_thermal kvm_intel kvm irqbypass crc32c_intel video nfsd auth_rpcgss oid_registry nfs_acl lockd grace sunrpc sch_fq_codel ip_tables unix ipv6 autofs4<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: CPU: 5 PID: 22606 Comm: lament Tainted: G        W       4.14.30.mx64.211 #1<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: Hardware name: Dell Inc. OptiPlex 7010/0KRC95, BIOS A13 03/25/2013<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: task: ffff88016f0e62c0 task.stack: ffffc900033c4000<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RIP: 0010:g84_fifo_chan_engine_fini+0x7b/0x2d0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RSP: 0018:ffffc900033c7a20 EFLAGS: 00010282<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RAX: 000000000000001d RBX: ffff880213e77460 RCX: 0000000000000000<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RDX: ffff88021dd5bd70 RSI: ffff88021dd553d8 RDI: ffff88021dd553d8<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RBP: ffff880212c29c00 R08: 00000000000073c0 R09: 0000000000000001<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: R10: ffffffff8255ba20 R11: 0000000000000001 R12: ffff8801ff136008<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: R13: 0000000000000020 R14: ffff8801efc57000 R15: 0002a64b491e50e0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: FS:  00007f2ca92a5c40(0000) GS:ffff88021dd40000(0000) knlGS:0000000000000000<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: CR2: 00007f77ade57a10 CR3: 000000000240a004 CR4: 00000000001606e0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: Call Trace:<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_fifo_chan_child_fini+0x63/0xf0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_oproxy_fini+0x2b/0x80 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_object_fini+0xb8/0x210 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_object_fini+0x73/0x210 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_ioctl_del+0x7e/0xa0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvkm_ioctl+0x10a/0x240 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nvif_object_fini+0x49/0x60 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nouveau_channel_del+0x6e/0x110 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nouveau_abi16_chan_fini.isra.0+0xa1/0xe0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nouveau_abi16_fini+0x2d/0x70 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  nouveau_drm_postclose+0x4b/0xd0 [nouveau]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  drm_release+0x264/0x380 [drm]<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  __fput+0xa6/0x1e0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  task_work_run+0x7e/0xa0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  do_exit+0x2bc/0xb20<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  do_group_exit+0x33/0xa0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  get_signal+0x1e4/0x570<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  do_signal+0x23/0x5c0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  ? update_curr+0x11e/0x170<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  ? update_curr+0xb9/0x170<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  ? pick_next_entity+0x70/0x110<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  ? sched_clock_cpu+0x11/0xb0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  ? __schedule+0xe9/0x790<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  exit_to_usermode_loop+0x79/0x90<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  do_syscall_64+0xf0/0x110<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel:  entry_SYSCALL_64_after_hwframe+0x3d/0xa2<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RIP: 0033:0x7f2ca63a1727<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RSP: 002b:00007ffe232fa378 EFLAGS: 00000246 ORIG_RAX: 0000000000000018<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RAX: 0000000000000000 RBX: 00000000000689c8 RCX: 00007f2ca63a1727<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000913570<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: RBP: 0000000000c0a9d0 R08: 000000000091aaa0 R09: 000000000091aaa0<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: R10: 00000000009134c0 R11: 0000000000000246 R12: 0000000000913570<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: R13: 0000000000000001 R14: 0000000000000000 R15: 0000000000000000<br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: Code: 7d a0 e1 48 8b 7d 10 48 8b 5f 50 48 85 db 0f 84 5d 02 00 00 e8 67 aa 0d e1 48 89 da 48 89 c6 48 c7 c7 b8 1a 50 a0 e8 f0 7c ca e0 <0f> 0b 48 8b 75 78 8b 7c 24 08 48 81 c6 20 25 00 00 e8 4f f7 ff <br>
Apr 14 02:19:40 bicoid.molgen.mpg.de kernel: ---[ end trace 4c650f0757036e92 ]---<br>
<br>
Please find the Linux and X server messages attached.<br>
  <br>
**Attachment 138869**, "Linux 4.14.30 messages":  <br>
[20180413â__bicoidâ__nouveauâ__journalctl.txt](/uploads/ff2586a78fc318fc282efd24ce6d37d5/20180413â__bicoidâ__nouveauâ__journalctl.txt)<br>