igt@kms_pipe_crc_basic@suspend-read-crc-pipe-b Stdout An internal exception that should have been handled was not: Test run time exceeded timeout value (600 seconds) dmesg [ 398.679026] Suspending console(s) (use no_console_suspend to debug) [ 398.713767] hpet1: lost 1 rtc interrupts [ 398.788102] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 398.790703] sd 0:0:0:0: [sda] Stopping disk [ 399.032582] cache: parent cpu1 should not be sleeping [ 399.035491] cache: parent cpu2 should not be sleeping [ 399.038402] cache: parent cpu3 should not be sleeping [ 399.041416] cache: parent cpu4 should not be sleeping [ 399.044268] cache: parent cpu5 should not be sleeping [ 399.047202] cache: parent cpu6 should not be sleeping [ 399.050155] cache: parent cpu7 should not be sleeping [ 399.081083] sd 0:0:0:0: [sda] Starting disk [ 399.225519] done. [ 409.828392] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1008.100396] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1018.340390] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:38:primary B] flip_done timed out [ 1028.580396] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1038.820450] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1049.060395] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:56:DP-1] flip_done timed out [ 1059.300398] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:38:primary B] flip_done timed out [ 1059.408381] ------------[ cut here ]------------ [ 1059.408383] vblank wait timed out on crtc 0 [ 1059.408433] WARNING: CPU: 1 PID: 15543 at drivers/gpu/drm/drm_vblank.c:1073 drm_wait_one_vblank+0x18d/0x1a0 [drm] [ 1059.408433] Modules linked in: vgem ip6table_filter ip6_tables iptable_filter snd_hda_codec_hdmi intel_rapl snd_hda_codec_realtek x86_pkg_temp_thermal intel_powerclamp snd_hda_codec_generic coretemp kvm_intel kvm snd_hda_intel snd_hda_codec irqbypass snd_hda_core snd_hwdep crct10dif_pclmul crc32_pclmul snd_pcm ghash_clmulni_intel pcbc snd_seq_midi snd_seq_midi_event binfmt_misc snd_rawmidi aesni_intel aes_x86_64 crypto_simd nls_iso8859_1 glue_helper cryptd snd_seq dcdbas intel_cstate snd_seq_device intel_rapl_perf snd_timer serio_raw snd mei_me mei soundcore shpchp lpc_ich mac_hid parport_pc ppdev lp parport ip_tables x_tables autofs4 i915 i2c_algo_bit prime_numbers drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm e1000e psmouse ahci libahci ptp pps_core video [ 1059.408458] CPU: 1 PID: 15543 Comm: kms_pipe_crc_ba Tainted: G U 4.15.0-rc2-drm-intel-qa-ww49-commit-1a0d67e+ #1 [ 1059.408458] Hardware name: Dell Inc. OptiPlex 990/06D7TR, BIOS A19 08/26/2015 [ 1059.408459] task: 000000002f16a5d4 task.stack: 00000000b4742322 [ 1059.408466] RIP: 0010:drm_wait_one_vblank+0x18d/0x1a0 [drm] [ 1059.408466] RSP: 0018:ffffa534c272f970 EFLAGS: 00010286 [ 1059.408467] RAX: 0000000000000000 RBX: ffff8fd190220000 RCX: 0000000000000006 [ 1059.408468] RDX: 0000000000000007 RSI: 0000000000000092 RDI: ffff8fd19d24dcd0 [ 1059.408469] RBP: 0000000000000000 R08: 0000000000000001 R09: 000000000001860a [ 1059.408469] R10: 0000000000000000 R11: 000000000001860a R12: 0000000000000000 [ 1059.408470] R13: 0000000000000000 R14: 000000000000512b R15: ffff8fd19aa87c08 [ 1059.408471] FS: 00007f2d51f2ca00(0000) GS:ffff8fd19d240000(0000) knlGS:0000000000000000 [ 1059.408471] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1059.408472] CR2: 00007f66c05091a9 CR3: 000000032a609004 CR4: 00000000000606e0 [ 1059.408473] Call Trace: [ 1059.408479] ? wait_woken+0x80/0x80 [ 1059.408500] ironlake_crtc_enable+0x792/0xbf0 [i915] [ 1059.408520] intel_update_crtc+0x3d/0x90 [i915] [ 1059.408538] intel_update_crtcs+0x57/0x70 [i915] [ 1059.408555] intel_atomic_commit_tail+0x39d/0xdd0 [i915] [ 1059.408573] ? intel_atomic_commit_ready+0x44/0x4c [i915] [ 1059.408590] intel_atomic_commit+0x1fd/0x2a0 [i915] [ 1059.408594] restore_fbdev_mode_atomic+0x1ab/0x220 [drm_kms_helper] [ 1059.408599] drm_fb_helper_restore_fbdev_mode_unlocked+0x45/0x90 [drm_kms_helper] [ 1059.408617] intel_fbdev_restore_mode+0x30/0x80 [i915] [ 1059.408631] i915_driver_lastclose+0xa/0x10 [i915] [ 1059.408638] drm_lastclose+0x34/0xf0 [drm] [ 1059.408644] drm_release+0x2b6/0x370 [drm] [ 1059.408646] __fput+0xd5/0x210 [ 1059.408649] task_work_run+0x80/0xa0 [ 1059.408650] do_exit+0x2d7/0xaf0 [ 1059.408652] do_group_exit+0x3a/0xa0 [ 1059.408653] get_signal+0x29a/0x5e0 [ 1059.408656] do_signal+0x36/0x6c0 [ 1059.408658] ? __fpu__restore_sig+0x97/0x470 [ 1059.408661] exit_to_usermode_loop+0x7b/0xc0 [ 1059.408663] do_syscall_64+0xe4/0x110 [ 1059.408665] entry_SYSCALL64_slow_path+0x25/0x25 [ 1059.408666] RIP: 0033:0x7f2d50119b38 [ 1059.408666] RSP: 002b:00007ffd24e0ea00 EFLAGS: 00000246 [ 1059.408667] RAX: 0000000000000101 RBX: 0000000001428940 RCX: 00007f2d50119b3a [ 1059.408668] RDX: 0000000000000000 RSI: 00007ffd24e0ea80 RDI: 0000000000000006 [ 1059.408669] RBP: 000000000043317e R08: 0000000000000001 R09: 000000000000000f [ 1059.408669] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000004 [ 1059.408670] R13: 0000000000000001 R14: 0000000001420a40 R15: 0000000000645910 [ 1059.408671] Code: 0a ff ff ff e8 85 b9 dc cb 48 89 e6 4c 89 ff e8 2a e6 e0 cb 45 85 ed 0f 85 14 ff ff ff 89 ee 48 c7 c7 38 f5 2c c0 e8 c3 b6 dc cb <0f> ff e9 ff fe ff ff 66 90 66 2e 0f 1f 84 00 00 00 00 00 66 66 [ 1059.408687] ---[ end trace 85c462e7447a7387 ]--- [ 1069.540395] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 1079.780399] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out igt@prime_busy@basic-wait-before-default Stdout IGT-Version: 1.20-g1db1246 (x86_64) (Linux: 4.15.0-rc2-drm-intel-qa-ww49-commit-1a0d67e+ x86_64) Stack trace: #0 [__igt_fail_assert+0x101] #1 [busy+0x4c6] #2 [test_engine_mode+0x1ad] #3 [__real_main218+0xbf] #4 [main+0x23] #5 [__libc_start_main+0xf1] #6 [_start+0x29] #7 [+0x29] Subtest basic-wait-before-default: FAIL (1.069s) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Stderr (prime_busy:12344) CRITICAL: Test assertion failure function busy, file prime_busy.c:156: (prime_busy:12344) CRITICAL: Failed assertion: poll(pfd, 1, timeout * 1000) == 1 Subtest basic-wait-before-default failed. **** DEBUG **** (prime_busy:12344) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (prime_busy:12344) DEBUG: Test requirement passed: __gem_execbuf(fd, &execbuf) == 0 (prime_busy:12344) CRITICAL: Test assertion failure function busy, file prime_busy.c:156: (prime_busy:12344) CRITICAL: Failed assertion: poll(pfd, 1, timeout * 1000) == 1 (prime_busy:12344) igt-core-INFO: Stack trace: (prime_busy:12344) igt-core-INFO: #0 [__igt_fail_assert+0x101] (prime_busy:12344) igt-core-INFO: #1 [busy+0x4c6] (prime_busy:12344) igt-core-INFO: #2 [test_engine_mode+0x1ad] (prime_busy:12344) igt-core-INFO: #3 [__real_main218+0xbf] (prime_busy:12344) igt-core-INFO: #4 [main+0x23] (prime_busy:12344) igt-core-INFO: #5 [__libc_start_main+0xf1] (prime_busy:12344) igt-core-INFO: #6 [_start+0x29] (prime_busy:12344) igt-core-INFO: #7 [+0x29] **** END **** dmesg [ 1991.984782] Setting dangerous option reset - tainting kernel [ 1993.054602] Setting dangerous option reset - tainting kernel [ 1993.054928] Setting dangerous option reset - tainting kernel [ 1993.054999] Setting dangerous option reset - tainting kernel [ 1993.055334] Setting dangerous option reset - tainting kernel [ 1993.055683] Setting dangerous option reset - tainting kernel [ 1993.055768] Setting dangerous option reset - tainting kernel [ 1993.056110] Setting dangerous option reset - tainting kernel [ 1993.056586] Setting dangerous option reset - tainting kernel [ 2003.172415] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 2013.412417] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 2023.652419] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:48:VGA-1] flip_done timed out [ 2033.892422] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:56:DP-1] flip_done timed out [ 2044.132423] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:primary A] flip_done timed out [ 2054.372417] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:38:primary B] flip_done timed out [ 2064.612424] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 2074.852417] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out igt@prime_busy@basic-before-default Stdout IGT-Version: 1.20-g1db1246 (x86_64) (Linux: 4.15.0-rc2-drm-intel-qa-ww49-commit-1a0d67e+ x86_64) Stack trace: #0 [__igt_fail_assert+0x101] #1 [busy+0x51a] #2 [test_engine_mode+0x106] #3 [__real_main218+0xbf] #4 [main+0x23] #5 [__libc_start_main+0xf1] #6 [_start+0x29] #7 [+0x29] Subtest basic-before-default: FAIL (1.155s) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Stderr (prime_busy:9801) CRITICAL: Test assertion failure function busy, file prime_busy.c:160: (prime_busy:9801) CRITICAL: Failed assertion: igt_seconds_elapsed(&tv) < timeout Subtest basic-before-default failed. **** DEBUG **** (prime_busy:9801) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (prime_busy:9801) DEBUG: Test requirement passed: __gem_execbuf(fd, &execbuf) == 0 (prime_busy:9801) CRITICAL: Test assertion failure function busy, file prime_busy.c:160: (prime_busy:9801) CRITICAL: Failed assertion: igt_seconds_elapsed(&tv) < timeout (prime_busy:9801) igt-core-INFO: Stack trace: (prime_busy:9801) igt-core-INFO: #0 [__igt_fail_assert+0x101] (prime_busy:9801) igt-core-INFO: #1 [busy+0x51a] (prime_busy:9801) igt-core-INFO: #2 [test_engine_mode+0x106] (prime_busy:9801) igt-core-INFO: #3 [__real_main218+0xbf] (prime_busy:9801) igt-core-INFO: #4 [main+0x23] (prime_busy:9801) igt-core-INFO: #5 [__libc_start_main+0xf1] (prime_busy:9801) igt-core-INFO: #6 [_start+0x29] (prime_busy:9801) igt-core-INFO: #7 [+0x29] **** END **** dmesg [ 1825.364504] Setting dangerous option reset - tainting kernel [ 1826.520092] Setting dangerous option reset - tainting kernel [ 1826.520446] Setting dangerous option reset - tainting kernel [ 1826.520511] Setting dangerous option reset - tainting kernel [ 1826.520831] Setting dangerous option reset - tainting kernel [ 1826.521157] Setting dangerous option reset - tainting kernel [ 1826.521256] Setting dangerous option reset - tainting kernel [ 1826.521588] Setting dangerous option reset - tainting kernel [ 1826.521912] Setting dangerous option reset - tainting kernel [ 1836.772415] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 1847.012413] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1857.252415] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:48:VGA-1] flip_done timed out [ 1867.492411] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:56:DP-1] flip_done timed out [ 1877.732414] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:primary A] flip_done timed out [ 1887.972412] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:38:primary B] flip_done timed out [ 1898.212415] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 1908.452382] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out igt@prime_busy@basic-after-default Stdout IGT-Version: 1.20-g1db1246 (x86_64) (Linux: 4.15.0-rc2-drm-intel-qa-ww49-commit-1a0d67e+ x86_64) Stack trace: #0 [__igt_fail_assert+0x101] #1 [busy+0x51a] #2 [test_engine_mode+0x106] #3 [__real_main218+0xbf] #4 [main+0x23] #5 [__libc_start_main+0xf1] #6 [_start+0x29] #7 [+0x29] Subtest basic-after-default: FAIL (1.143s) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function test_engine_mode, file prime_busy.c:186: Test requirement: gem_can_store_dword(fd, e->exec_id | e->flags) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Test requirement not met in function gem_require_ring, file ioctl_wrappers.c:1460: Test requirement: gem_has_ring(fd, ring) Stderr (prime_busy:8713) CRITICAL: Test assertion failure function busy, file prime_busy.c:160: (prime_busy:8713) CRITICAL: Failed assertion: igt_seconds_elapsed(&tv) < timeout Subtest basic-after-default failed. **** DEBUG **** (prime_busy:8713) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (prime_busy:8713) DEBUG: Test requirement passed: __gem_execbuf(fd, &execbuf) == 0 (prime_busy:8713) CRITICAL: Test assertion failure function busy, file prime_busy.c:160: (prime_busy:8713) CRITICAL: Failed assertion: igt_seconds_elapsed(&tv) < timeout (prime_busy:8713) igt-core-INFO: Stack trace: (prime_busy:8713) igt-core-INFO: #0 [__igt_fail_assert+0x101] (prime_busy:8713) igt-core-INFO: #1 [busy+0x51a] (prime_busy:8713) igt-core-INFO: #2 [test_engine_mode+0x106] (prime_busy:8713) igt-core-INFO: #3 [__real_main218+0xbf] (prime_busy:8713) igt-core-INFO: #4 [main+0x23] (prime_busy:8713) igt-core-INFO: #5 [__libc_start_main+0xf1] (prime_busy:8713) igt-core-INFO: #6 [_start+0x29] (prime_busy:8713) igt-core-INFO: #7 [+0x29] **** END **** dmesg [ 1741.884528] Setting dangerous option reset - tainting kernel [ 1741.884885] Setting dangerous option reset - tainting kernel [ 1743.028063] Setting dangerous option reset - tainting kernel [ 1743.028132] Setting dangerous option reset - tainting kernel [ 1743.028464] Setting dangerous option reset - tainting kernel [ 1743.028745] Setting dangerous option reset - tainting kernel [ 1743.028803] Setting dangerous option reset - tainting kernel [ 1743.029116] Setting dangerous option reset - tainting kernel [ 1743.029494] Setting dangerous option reset - tainting kernel [ 1753.316412] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 1763.556393] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out [ 1773.796413] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:48:VGA-1] flip_done timed out [ 1784.036414] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:56:DP-1] flip_done timed out [ 1794.276411] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:primary A] flip_done timed out [ 1804.516415] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:38:primary B] flip_done timed out [ 1814.756415] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out [ 1824.996415] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:47:pipe B] flip_done timed out