[ 39.273823] gem_ringfill: executing [ 39.279187] gem_ringfill: starting subtest basic-default-S3 [ 39.847229] PM: Syncing filesystems ... done. [ 39.856833] Freezing user space processes ... (elapsed 0.002 seconds) done. [ 39.859025] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done. [ 39.860104] Suspending console(s) (use no_console_suspend to debug) [ 39.865679] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 39.866013] system 00:00: System wakeup disabled by ACPI [ 39.867536] e1000e: EEE TX LPI TIMER: 00000011 [ 39.868674] sd 0:0:0:0: [sda] Stopping disk [ 39.921184] PM: suspend of devices complete after 60.117 msecs [ 39.934016] PM: late suspend of devices complete after 12.820 msecs [ 39.937731] e1000e 0000:00:1f.6: System wakeup enabled by ACPI [ 39.940105] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 39.966463] PM: noirq suspend of devices complete after 32.425 msecs [ 39.966927] ACPI: Preparing to enter system sleep state S3 [ 39.978504] PM: Saving platform NVS memory [ 39.978659] Disabling non-boot CPUs ... [ 39.990189] smpboot: CPU 1 is now offline [ 40.005234] smpboot: CPU 2 is now offline [ 40.018563] smpboot: CPU 3 is now offline [ 40.024151] ACPI: Low-level resume complete [ 40.024376] PM: Restoring platform NVS memory [ 40.025885] Enabling non-boot CPUs ... [ 40.026469] x86: Booting SMP configuration: [ 40.026471] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 40.031142] cache: parent cpu1 should not be sleeping [ 40.033848] CPU1 is up [ 40.034123] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 40.038095] cache: parent cpu2 should not be sleeping [ 40.040081] CPU2 is up [ 40.040266] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 40.044530] cache: parent cpu3 should not be sleeping [ 40.046534] CPU3 is up [ 40.050192] ACPI: Waking up from system sleep state S3 [ 40.283530] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 40.284052] PM: noirq resume of devices complete after 31.294 msecs [ 40.356048] PM: early resume of devices complete after 71.876 msecs [ 40.358032] e1000e 0000:00:1f.6: System wakeup disabled by ACPI [ 40.359095] sd 0:0:0:0: [sda] Starting disk [ 40.375884] rtc_cmos 00:03: System wakeup disabled by ACPI [ 40.663331] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 40.678537] ata1.00: configured for UDMA/133 [ 41.405631] PM: resume of devices complete after 1049.004 msecs [ 41.409222] Restarting tasks ... done. [ 41.488215] video LNXVIDEO:00: Restoring backlight state [ 41.744085] [drm] RC6 on [ 43.733399] ------------[ cut here ]------------ [ 43.733407] WARNING: CPU: 3 PID: 5679 at sound/hda/hdac_i915.c:91 snd_hdac_display_power+0xf1/0x110 [snd_hda_core]() [ 43.733409] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core mei_me mei snd_pcm i2c_hid sdhci_pci e1000e sdhci ptp mmc_core pps_core [ 43.733425] CPU: 3 PID: 5679 Comm: kworker/3:3 Tainted: G W 4.5.0-gfxbench+ #1 [ 43.733426] Hardware name: /NUC6i5SYB, BIOS SYSKLi35.86A.0028.2015.1112.1822 11/12/2015 [ 43.733429] Workqueue: pm pm_runtime_work [ 43.733431] 0000000000000000 ffff88024cecfbe8 ffffffff813fef15 0000000000000000 [ 43.733433] ffffffffa00f877c ffff88024cecfc20 ffffffff81078a21 0000000000000000 [ 43.733436] ffff88026661d668 ffff880263ee10d8 ffff880265bc5668 0000000000000000 [ 43.733438] Call Trace: [ 43.733441] [] dump_stack+0x67/0x92 [ 43.733445] [] warn_slowpath_common+0x81/0xc0 [ 43.733448] [] warn_slowpath_null+0x15/0x20 [ 43.733453] [] snd_hdac_display_power+0xf1/0x110 [snd_hda_core] [ 43.733457] [] azx_intel_link_power+0xd/0x10 [snd_hda_intel] [ 43.733464] [] azx_link_power+0x1a/0x30 [snd_hda_codec] [ 43.733469] [] snd_hdac_link_power+0x29/0x40 [snd_hda_core] [ 43.733474] [] hda_codec_runtime_suspend+0x76/0xa0 [snd_hda_codec] [ 43.733479] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 43.733497] [] __rpm_callback+0x2d/0x70 [ 43.733500] [] rpm_callback+0x1f/0x80 [ 43.733505] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 43.733508] [] rpm_suspend+0x134/0x7f0 [ 43.733510] [] pm_runtime_work+0x76/0xc0 [ 43.733512] [] process_one_work+0x1cb/0x680 [ 43.733513] [] ? process_one_work+0x146/0x680 [ 43.733515] [] worker_thread+0x49/0x490 [ 43.733517] [] ? process_one_work+0x680/0x680 [ 43.733518] [] ? process_one_work+0x680/0x680 [ 43.733520] [] kthread+0xea/0x100 [ 43.733523] [] ? _raw_spin_unlock_irq+0x27/0x50 [ 43.733525] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 43.733527] [] ret_from_fork+0x3f/0x70 [ 43.733529] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 43.733531] ---[ end trace 74f6ab5178db7258 ]--- [ 43.867469] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx [ 56.277972] gem_ringfill: exiting, ret=0 [ 56.578674] gem_ctx_param_basic: executing [ 56.588669] gem_ctx_param_basic: starting subtest root-set-no-zeromap-enabled [ 56.588907] gem_ctx_param_basic: exiting, ret=0 [ 56.652673] gem_mmap_gtt: executing [ 56.658675] gem_mmap_gtt: starting subtest basic [ 56.659389] gem_mmap_gtt: exiting, ret=0 [ 56.750749] pm_backlight: executing [ 56.751299] pm_backlight: exiting, ret=0 [ 56.826438] kms_addfb_basic: executing [ 56.832805] kms_addfb_basic: starting subtest no-handle [ 56.833536] kms_addfb_basic: exiting, ret=0 [ 56.919828] gem_pwrite: executing [ 56.927613] gem_pwrite: starting subtest basic [ 57.421545] gem_pwrite: exiting, ret=0 [ 57.486456] kms_pipe_crc_basic: executing [ 57.496144] kms_pipe_crc_basic: starting subtest bad-pipe [ 57.496348] kms_pipe_crc_basic: exiting, ret=0 [ 57.573277] kms_force_connector_basic: executing [ 57.579813] kms_force_connector_basic: exiting, ret=0 [ 57.643201] gem_flink_basic: executing [ 57.649063] gem_flink_basic: starting subtest double-flink [ 57.649235] gem_flink_basic: exiting, ret=0 [ 57.720308] gem_ctx_basic: executing [ 64.974081] gem_ctx_basic: exiting, ret=0 [ 65.061433] gem_ringfill: executing [ 65.067864] gem_ringfill: starting subtest basic-default-child [ 65.509934] gem_ringfill: exiting, ret=0 [ 65.572095] gem_exec_basic: executing [ 65.578237] gem_exec_basic: starting subtest basic-bsd [ 65.578604] gem_exec_basic: exiting, ret=0 [ 65.650566] gem_ctx_param_basic: executing [ 65.656404] gem_ctx_param_basic: starting subtest invalid-ctx-get [ 65.656661] gem_ctx_param_basic: exiting, ret=0 [ 65.733114] gem_exec_basic: executing [ 65.740802] gem_exec_basic: starting subtest gtt-render [ 65.741953] gem_exec_basic: exiting, ret=0 [ 65.830782] kms_pipe_crc_basic: executing [ 65.843091] kms_pipe_crc_basic: starting subtest read-crc-pipe-C [ 66.204382] kms_pipe_crc_basic: exiting, ret=0 [ 66.360649] gem_exec_basic: executing [ 66.367960] gem_exec_basic: starting subtest basic-render [ 66.368468] gem_exec_basic: exiting, ret=0 [ 66.454101] kms_addfb_basic: executing [ 66.462154] kms_addfb_basic: starting subtest addfb25-modifier-no-flag [ 66.462635] kms_addfb_basic: exiting, ret=0 [ 66.539147] gem_ctx_param_basic: executing [ 66.545685] gem_ctx_param_basic: starting subtest root-set-no-zeromap-disabled [ 66.545892] gem_ctx_param_basic: exiting, ret=0 [ 66.634645] kms_pipe_crc_basic: executing [ 66.644336] kms_pipe_crc_basic: starting subtest bad-nb-words-3 [ 66.644680] kms_pipe_crc_basic: exiting, ret=0 [ 66.727103] gem_ringfill: executing [ 66.734843] gem_ringfill: starting subtest basic-default [ 67.179178] gem_ringfill: exiting, ret=0 [ 67.262691] gem_exec_basic: executing [ 67.271780] gem_exec_basic: starting subtest gtt-bsd [ 67.272505] gem_exec_basic: exiting, ret=0 [ 67.337412] pm_rps: executing [ 67.345275] pm_rps: starting subtest basic-api [ 67.347133] pm_rps: exiting, ret=0 [ 67.421862] gem_cs_tlb: executing [ 67.428419] gem_cs_tlb: starting subtest basic-default [ 68.674196] gem_cs_tlb: exiting, ret=0 [ 68.800062] gem_ctx_exec: executing [ 68.806296] gem_ctx_exec: starting subtest basic [ 68.807995] gem_ctx_exec: exiting, ret=0 [ 68.894035] kms_addfb_basic: executing [ 68.901377] kms_addfb_basic: starting subtest bad-pitch-0 [ 68.902295] kms_addfb_basic: exiting, ret=0 [ 68.974086] gem_ctx_param_basic: executing [ 68.979823] gem_ctx_param_basic: starting subtest invalid-ctx-set [ 68.980051] gem_ctx_param_basic: exiting, ret=0 [ 69.055125] gem_exec_basic: executing [ 69.061614] gem_exec_basic: starting subtest basic-vebox [ 69.062091] gem_exec_basic: exiting, ret=0 [ 69.126999] gem_mmap_gtt: executing [ 69.133183] gem_mmap_gtt: starting subtest basic-read-write-distinct [ 69.150444] gem_mmap_gtt: exiting, ret=0 [ 69.224458] kms_pipe_crc_basic: executing [ 69.233644] kms_pipe_crc_basic: starting subtest read-crc-pipe-B-frame-sequence [ 69.604454] kms_pipe_crc_basic: exiting, ret=0 [ 69.765807] kms_addfb_basic: executing [ 69.772510] kms_addfb_basic: starting subtest unused-offsets [ 69.773304] kms_addfb_basic: exiting, ret=0 [ 69.847155] gem_exec_store: executing [ 69.853405] gem_exec_store: starting subtest basic-blt [ 69.854006] gem_exec_store: exiting, ret=0 [ 69.921136] kms_frontbuffer_tracking: executing [ 69.929713] kms_frontbuffer_tracking: starting subtest basic [ 71.884580] kms_frontbuffer_tracking: exiting, ret=0 [ 72.009455] gem_storedw_loop: executing [ 72.015507] gem_storedw_loop: starting subtest basic-default [ 75.403106] gem_storedw_loop: exiting, ret=0 [ 75.713744] gem_basic: executing [ 75.721423] gem_basic: starting subtest bad-close [ 75.721563] gem_basic: exiting, ret=0 [ 75.799297] core_auth: executing [ 75.800004] core_auth: starting subtest basic-auth [ 75.800489] core_auth: exiting, ret=0 [ 75.882151] kms_addfb_basic: executing [ 75.889770] kms_addfb_basic: starting subtest unused-handle [ 75.890469] kms_addfb_basic: exiting, ret=0 [ 75.974479] kms_setmode: executing [ 75.981033] kms_setmode: starting subtest basic-clone-single-crtc [ 76.089649] kms_setmode: exiting, ret=0 [ 76.186066] kms_pipe_crc_basic: executing [ 76.195383] kms_pipe_crc_basic: starting subtest read-crc-pipe-A [ 76.524724] kms_pipe_crc_basic: exiting, ret=0 [ 76.641070] kms_addfb_basic: executing [ 76.647896] kms_addfb_basic: starting subtest addfb25-framebuffer-vs-set-tiling [ 76.648236] kms_addfb_basic: exiting, ret=0 [ 76.718457] gem_ringfill: executing [ 76.724927] gem_ringfill: starting subtest basic-default-interruptible [ 77.198983] gem_ringfill: exiting, ret=0 [ 77.264101] kms_force_connector_basic: executing [ 77.269444] kms_force_connector_basic: exiting, ret=0 [ 77.342285] gem_sync: executing [ 77.348588] gem_sync: starting subtest basic-render [ 87.450277] gem_sync: exiting, ret=0 [ 87.737192] kms_pipe_crc_basic: executing [ 87.750531] kms_pipe_crc_basic: starting subtest nonblocking-crc-pipe-C-frame-sequence [ 88.132723] kms_pipe_crc_basic: exiting, ret=0 [ 88.295362] gem_sync: executing [ 88.302118] gem_sync: starting subtest basic-bsd1 [ 99.293396] gem_sync: exiting, ret=0 [ 99.588433] gem_sync: executing [ 99.594794] gem_sync: starting subtest basic-vebox [ 110.430847] gem_sync: exiting, ret=0 [ 110.695945] drv_hangman: executing [ 110.696154] drv_hangman: starting subtest error-state-basic [ 118.773846] [drm] stuck on render ring [ 118.774871] [drm] GPU HANG: ecode 9:0:0xe757fffe, in drv_hangman [5847], reason: Ring hung, action: reset [ 118.774885] [drm] GPU hangs can indicate a bug anywhere in the entire gfx stack, including userspace. [ 118.774887] [drm] Please file a _new_ bug report on bugs.freedesktop.org against DRI -> DRM/Intel [ 118.774890] [drm] drm/i915 developers can then reassign to the right component if it's not a kernel issue. [ 118.774892] [drm] The gpu crash dump is required to analyze gpu hangs, so please always attach it. [ 118.774895] [drm] GPU crash dump saved to /sys/class/drm/card0/error [ 118.779101] drm/i915: Resetting chip after gpu hang [ 118.782786] drv_hangman: exiting, ret=0 [ 119.056014] gem_flink_basic: executing [ 119.060973] gem_flink_basic: starting subtest basic [ 119.061149] gem_flink_basic: exiting, ret=0 [ 119.129964] kms_pipe_crc_basic: executing [ 119.139278] kms_pipe_crc_basic: starting subtest hang-read-crc-pipe-A [ 120.775544] [drm] RC6 on [ 126.778605] [drm] stuck on render ring [ 126.781277] [drm] GPU HANG: ecode 9:0:0xfffffffe, in kms_pipe_crc_ba [5854], reason: Ring hung, action: reset [ 126.784360] [drm] Simulated gpu hang, resetting stop_rings [ 126.784363] drm/i915: Resetting chip after gpu hang [ 127.104052] kms_pipe_crc_basic: exiting, ret=0 [ 127.455127] gem_ctx_param_basic: executing [ 127.462297] gem_ctx_param_basic: starting subtest invalid-size-set [ 127.462738] gem_ctx_param_basic: exiting, ret=0 [ 127.527609] gem_ctx_param_basic: executing [ 127.533893] gem_ctx_param_basic: starting subtest basic [ 127.534121] gem_ctx_param_basic: exiting, ret=0 [ 127.607938] gem_flink_basic: executing [ 127.614154] gem_flink_basic: starting subtest bad-flink [ 127.614261] gem_flink_basic: exiting, ret=0 [ 127.678532] gem_mmap_gtt: executing [ 127.684404] gem_mmap_gtt: starting subtest basic-write-cpu-read-gtt [ 127.788780] [drm] RC6 on [ 127.842955] gem_mmap_gtt: exiting, ret=0 [ 127.947158] kms_flip: executing [ 127.954910] kms_flip: starting subtest basic-flip-vs-dpms [ 188.473790] kms_flip: exiting, ret=0 [ 188.817154] kms_addfb_basic: executing [ 188.824708] kms_addfb_basic: starting subtest addfb25-X-tiled-mismatch [ 188.825150] kms_addfb_basic: exiting, ret=0 [ 188.892566] kms_pipe_crc_basic: executing [ 188.902456] kms_pipe_crc_basic: starting subtest suspend-read-crc-pipe-A [ 188.966340] PM: Syncing filesystems ... done. [ 188.983383] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 188.985020] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done. [ 188.986158] Suspending console(s) (use no_console_suspend to debug) [ 188.988030] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 188.988638] system 00:00: System wakeup disabled by ACPI [ 188.988733] e1000e: EEE TX LPI TIMER: 00000011 [ 188.990699] sd 0:0:0:0: [sda] Stopping disk [ 189.040096] PM: suspend of devices complete after 52.991 msecs [ 189.053026] PM: late suspend of devices complete after 12.917 msecs [ 189.057471] e1000e 0000:00:1f.6: System wakeup enabled by ACPI [ 189.061843] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 189.088513] PM: noirq suspend of devices complete after 35.463 msecs [ 189.089099] ACPI: Preparing to enter system sleep state S3 [ 189.101606] PM: Saving platform NVS memory [ 189.101739] Disabling non-boot CPUs ... [ 189.111488] smpboot: CPU 1 is now offline [ 189.123434] smpboot: CPU 2 is now offline [ 189.132430] smpboot: CPU 3 is now offline [ 189.137752] ACPI: Low-level resume complete [ 189.137980] PM: Restoring platform NVS memory [ 189.138986] Enabling non-boot CPUs ... [ 189.139291] x86: Booting SMP configuration: [ 189.139292] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 189.143598] cache: parent cpu1 should not be sleeping [ 189.146347] CPU1 is up [ 189.146532] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 189.150338] cache: parent cpu2 should not be sleeping [ 189.152298] CPU2 is up [ 189.152485] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 189.156490] cache: parent cpu3 should not be sleeping [ 189.158533] CPU3 is up [ 189.162118] ACPI: Waking up from system sleep state S3 [ 189.383446] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 189.383950] PM: noirq resume of devices complete after 27.526 msecs [ 189.455875] PM: early resume of devices complete after 71.822 msecs [ 189.458979] sd 0:0:0:0: [sda] Starting disk [ 189.459601] e1000e 0000:00:1f.6: System wakeup disabled by ACPI [ 189.474801] rtc_cmos 00:03: System wakeup disabled by ACPI [ 189.761894] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 189.777966] ata1.00: configured for UDMA/133 [ 189.903780] PM: resume of devices complete after 447.657 msecs [ 189.905431] Restarting tasks ... done. [ 189.980651] video LNXVIDEO:00: Restoring backlight state [ 190.305090] kms_pipe_crc_basic: exiting, ret=0 [ 190.432181] gem_ringfill: executing [ 190.440122] gem_ringfill: starting subtest basic-default-S4 [ 190.820901] [drm] RC6 on [ 191.283583] gem_ringfill: exiting, ret=0 [ 191.382507] gem_mmap_gtt: executing [ 191.387941] gem_mmap_gtt: starting subtest basic-short [ 191.444777] gem_mmap_gtt: exiting, ret=0 [ 191.525115] gem_ctx_param_basic: executing [ 191.531559] gem_ctx_param_basic: starting subtest non-root-set [ 191.534285] gem_ctx_param_basic: exiting, ret=0 [ 191.599898] gem_exec_basic: executing [ 191.605925] gem_exec_basic: starting subtest gtt-bsd1 [ 191.606524] gem_exec_basic: exiting, ret=0 [ 191.683107] gem_exec_basic: executing [ 191.689751] gem_exec_basic: starting subtest readonly-blt [ 191.690214] gem_exec_basic: exiting, ret=0 [ 191.766384] gem_storedw_loop: executing [ 191.772668] gem_storedw_loop: starting subtest basic-bsd [ 192.728167] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx [ 192.834163] ------------[ cut here ]------------ [ 192.834172] WARNING: CPU: 0 PID: 39 at sound/hda/hdac_i915.c:91 snd_hdac_display_power+0xf1/0x110 [snd_hda_core]() [ 192.834173] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core mei_me mei snd_pcm i2c_hid sdhci_pci e1000e sdhci ptp mmc_core pps_core [ 192.834196] CPU: 0 PID: 39 Comm: kworker/0:1 Tainted: G W 4.5.0-gfxbench+ #1 [ 192.834197] Hardware name: /NUC6i5SYB, BIOS SYSKLi35.86A.0028.2015.1112.1822 11/12/2015 [ 192.834200] Workqueue: pm pm_runtime_work [ 192.834201] 0000000000000000 ffff88026764bbe8 ffffffff813fef15 0000000000000000 [ 192.834204] ffffffffa00f877c ffff88026764bc20 ffffffff81078a21 0000000000000000 [ 192.834206] ffff88026661d668 ffff880263ee10d8 ffff880265bc5668 0000000000000000 [ 192.834209] Call Trace: [ 192.834212] [] dump_stack+0x67/0x92 [ 192.834215] [] warn_slowpath_common+0x81/0xc0 [ 192.834216] [] warn_slowpath_null+0x15/0x20 [ 192.834220] [] snd_hdac_display_power+0xf1/0x110 [snd_hda_core] [ 192.834223] [] azx_intel_link_power+0xd/0x10 [snd_hda_intel] [ 192.834228] [] azx_link_power+0x1a/0x30 [snd_hda_codec] [ 192.834231] [] snd_hdac_link_power+0x29/0x40 [snd_hda_core] [ 192.834235] [] hda_codec_runtime_suspend+0x76/0xa0 [snd_hda_codec] [ 192.834238] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 192.834241] [] __rpm_callback+0x2d/0x70 [ 192.834243] [] rpm_callback+0x1f/0x80 [ 192.834246] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 192.834248] [] rpm_suspend+0x134/0x7f0 [ 192.834250] [] pm_runtime_work+0x76/0xc0 [ 192.834252] [] process_one_work+0x1cb/0x680 [ 192.834254] [] ? process_one_work+0x146/0x680 [ 192.834256] [] worker_thread+0x49/0x490 [ 192.834257] [] ? process_one_work+0x680/0x680 [ 192.834259] [] ? process_one_work+0x680/0x680 [ 192.834261] [] kthread+0xea/0x100 [ 192.834264] [] ? _raw_spin_unlock_irq+0x27/0x50 [ 192.834266] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 192.834268] [] ret_from_fork+0x3f/0x70 [ 192.834270] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 192.834295] ---[ end trace 74f6ab5178db7259 ]--- [ 195.429754] gem_storedw_loop: exiting, ret=0 [ 195.746361] kms_addfb_basic: executing [ 195.754322] kms_addfb_basic: starting subtest size-max [ 195.754938] kms_addfb_basic: exiting, ret=0 [ 195.823006] kms_addfb_basic: executing [ 195.829082] kms_addfb_basic: starting subtest bo-too-small-due-to-tiling [ 195.829466] kms_addfb_basic: exiting, ret=0 [ 195.907036] gem_exec_store: executing [ 195.912939] gem_exec_store: starting subtest basic-vebox [ 195.913478] gem_exec_store: exiting, ret=0 [ 195.980388] gem_exec_basic: executing [ 195.986541] gem_exec_basic: starting subtest basic-bsd2 [ 195.987243] gem_exec_basic: exiting, ret=0 [ 196.064563] kms_pipe_crc_basic: executing [ 196.074521] kms_pipe_crc_basic: starting subtest read-crc-pipe-C-frame-sequence [ 196.448348] kms_pipe_crc_basic: exiting, ret=0 [ 196.600526] gem_mmap: executing [ 196.606189] gem_mmap: starting subtest basic-small-bo [ 196.873289] gem_mmap (5967): drop_caches: 3 [ 197.041775] gem_mmap: exiting, ret=0 [ 197.212184] kms_pipe_crc_basic: executing [ 197.221848] kms_pipe_crc_basic: starting subtest hang-read-crc-pipe-B [ 203.824539] [drm] stuck on render ring [ 203.828253] [drm] GPU HANG: ecode 9:0:0xfffffffe, in kms_pipe_crc_ba [5970], reason: Ring hung, action: reset [ 203.831943] [drm] Simulated gpu hang, resetting stop_rings [ 203.831945] drm/i915: Resetting chip after gpu hang [ 204.210911] kms_pipe_crc_basic: exiting, ret=0 [ 204.404714] kms_pipe_crc_basic: executing [ 204.413462] kms_pipe_crc_basic: starting subtest read-crc-pipe-A-frame-sequence [ 204.754166] kms_pipe_crc_basic: exiting, ret=0 [ 204.881940] kms_psr_sink_crc: executing [ 204.889136] Setting dangerous option enable_psr - tainting kernel [ 204.889393] kms_psr_sink_crc: exiting, ret=0 [ 204.889571] Setting dangerous option enable_psr - tainting kernel [ 204.966487] gem_mmap_gtt: executing [ 204.972807] gem_mmap_gtt: starting subtest basic-small-bo [ 204.992011] gem_mmap_gtt (5980): drop_caches: 3 [ 205.117173] gem_mmap_gtt: exiting, ret=0 [ 205.248191] kms_addfb_basic: executing [ 205.254878] kms_addfb_basic: starting subtest bad-pitch-999 [ 205.255426] kms_addfb_basic: exiting, ret=0 [ 205.339907] gem_exec_basic: executing [ 205.346102] gem_exec_basic: starting subtest gtt-bsd2 [ 205.346816] gem_exec_basic: exiting, ret=0 [ 205.450169] kms_pipe_crc_basic: executing [ 205.458924] kms_pipe_crc_basic: starting subtest nonblocking-crc-pipe-A [ 205.794122] kms_pipe_crc_basic: exiting, ret=0 [ 205.833342] [drm] RC6 on [ 205.917317] kms_addfb_basic: executing [ 205.923726] kms_addfb_basic: starting subtest small-bo [ 205.924198] kms_addfb_basic: exiting, ret=0 [ 205.996096] gem_storedw_loop: executing [ 206.002437] gem_storedw_loop: starting subtest basic-vebox [ 209.533681] gem_storedw_loop: exiting, ret=0 [ 209.965830] gem_flink_basic: executing [ 209.973063] gem_flink_basic: starting subtest flink-lifetime [ 209.974099] gem_flink_basic: exiting, ret=0 [ 210.052993] kms_addfb_basic: executing [ 210.060911] kms_addfb_basic: starting subtest addfb25-X-tiled [ 210.061362] kms_addfb_basic: exiting, ret=0 [ 210.157801] gem_ringfill: executing [ 210.163773] gem_ringfill: starting subtest basic-default-bomb [ 224.125206] gem_ringfill: exiting, ret=0 [ 224.406348] gem_sync: executing [ 224.412982] gem_sync: starting subtest basic-bsd2 [ 234.874232] gem_sync: exiting, ret=0 [ 235.165159] gem_exec_basic: executing [ 235.171356] gem_exec_basic: starting subtest readonly-bsd2 [ 235.172050] gem_exec_basic: exiting, ret=0 [ 235.237911] kms_addfb_basic: executing [ 235.243431] kms_addfb_basic: starting subtest bad-pitch-65536 [ 235.244170] kms_addfb_basic: exiting, ret=0 [ 235.347770] gem_exec_store: executing [ 235.354762] gem_exec_store: starting subtest basic-bsd1 [ 235.355409] gem_exec_store: exiting, ret=0 [ 235.442969] gem_mmap_gtt: executing [ 235.451413] gem_mmap_gtt: starting subtest basic-write-read [ 235.468576] gem_mmap_gtt: exiting, ret=0 [ 235.558997] gem_mmap_gtt: executing [ 235.566840] gem_mmap_gtt: starting subtest basic-read-write [ 235.583658] gem_mmap_gtt: exiting, ret=0 [ 235.649878] kms_addfb_basic: executing [ 235.656180] kms_addfb_basic: starting subtest basic-X-tiled [ 235.656716] kms_addfb_basic: exiting, ret=0 [ 235.733966] prime_self_import: executing [ 235.734217] prime_self_import: starting subtest basic-with_two_bos [ 235.751969] prime_self_import: exiting, ret=0 [ 235.837537] gem_ctx_param_basic: executing [ 235.844661] gem_ctx_param_basic: starting subtest basic-default [ 235.844925] gem_ctx_param_basic: exiting, ret=0 [ 235.926943] gem_tiled_blits: executing [ 235.932544] gem_tiled_blits: starting subtest basic [ 235.947597] gem_tiled_blits: exiting, ret=0 [ 236.015969] gem_linear_blits: executing [ 236.021686] gem_linear_blits: starting subtest basic [ 236.031821] gem_linear_blits: exiting, ret=0 [ 236.120359] kms_addfb_basic: executing [ 236.129147] kms_addfb_basic: starting subtest tile-pitch-mismatch [ 236.129976] kms_addfb_basic: exiting, ret=0 [ 236.200958] pm_rpm: executing [ 236.300331] ahci 0000:00:17.0: port does not support device sleep [ 237.314229] pm_rpm: starting subtest basic-rte [ 237.506233] pm_rpm: exiting, ret=0 [ 237.519555] ahci 0000:00:17.0: port does not support device sleep [ 237.634012] core_prop_blob: executing [ 237.635163] core_prop_blob: starting subtest basic [ 237.635589] core_prop_blob: exiting, ret=0 [ 237.722729] kms_pipe_crc_basic: executing [ 237.733758] kms_pipe_crc_basic: starting subtest suspend-read-crc-pipe-B [ 237.837736] PM: Syncing filesystems ... done. [ 237.856116] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 237.857824] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 237.859391] Suspending console(s) (use no_console_suspend to debug) [ 237.862180] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 237.863228] system 00:00: System wakeup disabled by ACPI [ 237.863407] e1000e: EEE TX LPI TIMER: 00000011 [ 237.864638] sd 0:0:0:0: [sda] Stopping disk [ 237.868381] [drm] RC6 on [ 237.923693] PM: suspend of devices complete after 62.951 msecs [ 237.936456] PM: late suspend of devices complete after 12.751 msecs [ 237.939700] e1000e 0000:00:1f.6: System wakeup enabled by ACPI [ 237.942831] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI [ 237.968925] PM: noirq suspend of devices complete after 32.448 msecs [ 237.969448] ACPI: Preparing to enter system sleep state S3 [ 237.980907] PM: Saving platform NVS memory [ 237.981037] Disabling non-boot CPUs ... [ 237.989828] smpboot: CPU 1 is now offline [ 238.000380] smpboot: CPU 2 is now offline [ 238.009753] Broke affinity for irq 120 [ 238.010840] smpboot: CPU 3 is now offline [ 238.016032] ACPI: Low-level resume complete [ 238.016258] PM: Restoring platform NVS memory [ 238.017232] Enabling non-boot CPUs ... [ 238.017564] x86: Booting SMP configuration: [ 238.017565] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 238.021862] cache: parent cpu1 should not be sleeping [ 238.024559] CPU1 is up [ 238.024747] smpboot: Booting Node 0 Processor 2 APIC 0x1 [ 238.028671] cache: parent cpu2 should not be sleeping [ 238.030650] CPU2 is up [ 238.030839] smpboot: Booting Node 0 Processor 3 APIC 0x3 [ 238.034832] cache: parent cpu3 should not be sleeping [ 238.036804] CPU3 is up [ 238.040375] ACPI: Waking up from system sleep state S3 [ 238.261880] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI [ 238.262334] PM: noirq resume of devices complete after 28.551 msecs [ 238.332316] PM: early resume of devices complete after 69.832 msecs [ 238.335350] sd 0:0:0:0: [sda] Starting disk [ 238.338298] e1000e 0000:00:1f.6: System wakeup disabled by ACPI [ 238.352304] rtc_cmos 00:03: System wakeup disabled by ACPI [ 238.639473] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 238.655476] ata1.00: configured for UDMA/133 [ 238.665949] ahci 0000:00:17.0: port does not support device sleep [ 238.854551] PM: resume of devices complete after 521.945 msecs [ 238.856596] Restarting tasks ... done. [ 238.940205] video LNXVIDEO:00: Restoring backlight state [ 239.303307] kms_pipe_crc_basic: exiting, ret=0 [ 239.467382] gem_storedw_loop: executing [ 239.474403] gem_storedw_loop: starting subtest basic-bsd1 [ 239.855413] [drm] RC6 on [ 239.855741] ------------[ cut here ]------------ [ 239.855750] WARNING: CPU: 1 PID: 87 at sound/hda/hdac_i915.c:91 snd_hdac_display_power+0xf1/0x110 [snd_hda_core]() [ 239.855752] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core mei_me mei snd_pcm i2c_hid sdhci_pci e1000e sdhci ptp mmc_core pps_core [ 239.855778] CPU: 1 PID: 87 Comm: kworker/1:2 Tainted: G U W 4.5.0-gfxbench+ #1 [ 239.855780] Hardware name: /NUC6i5SYB, BIOS SYSKLi35.86A.0028.2015.1112.1822 11/12/2015 [ 239.855784] Workqueue: pm pm_runtime_work [ 239.855786] 0000000000000000 ffff8802644f7be8 ffffffff813fef15 0000000000000000 [ 239.855791] ffffffffa00f877c ffff8802644f7c20 ffffffff81078a21 0000000000000000 [ 239.855795] ffff88026661d668 ffff880263ee10d8 ffff880265bc5668 0000000000000000 [ 239.855799] Call Trace: [ 239.855803] [] dump_stack+0x67/0x92 [ 239.855807] [] warn_slowpath_common+0x81/0xc0 [ 239.855810] [] warn_slowpath_null+0x15/0x20 [ 239.855815] [] snd_hdac_display_power+0xf1/0x110 [snd_hda_core] [ 239.855819] [] azx_intel_link_power+0xd/0x10 [snd_hda_intel] [ 239.855827] [] azx_link_power+0x1a/0x30 [snd_hda_codec] [ 239.855831] [] snd_hdac_link_power+0x29/0x40 [snd_hda_core] [ 239.855837] [] hda_codec_runtime_suspend+0x76/0xa0 [snd_hda_codec] [ 239.855843] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 239.855847] [] __rpm_callback+0x2d/0x70 [ 239.855850] [] rpm_callback+0x1f/0x80 [ 239.855855] [] ? hda_codec_runtime_resume+0x50/0x50 [snd_hda_codec] [ 239.855858] [] rpm_suspend+0x134/0x7f0 [ 239.855861] [] pm_runtime_work+0x76/0xc0 [ 239.855864] [] process_one_work+0x1cb/0x680 [ 239.855867] [] ? process_one_work+0x146/0x680 [ 239.855869] [] worker_thread+0x49/0x490 [ 239.855872] [] ? process_one_work+0x680/0x680 [ 239.855874] [] ? process_one_work+0x680/0x680 [ 239.855878] [] kthread+0xea/0x100 [ 239.855881] [] ? _raw_spin_unlock_irq+0x27/0x50 [ 239.855885] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 239.855888] [] ret_from_fork+0x3f/0x70 [ 239.855891] [] ? kthread_create_on_node+0x1f0/0x1f0 [ 239.855893] ---[ end trace 74f6ab5178db725a ]--- [ 241.639636] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx [ 242.485303] gem_storedw_loop: exiting, ret=0 [ 243.040970] kms_pipe_crc_basic: executing [ 243.051163] kms_pipe_crc_basic: starting subtest nonblocking-crc-pipe-A-frame-sequence [ 243.439605] kms_pipe_crc_basic: exiting, ret=0 [ 243.568047] kms_addfb_basic: executing [ 243.574130] kms_addfb_basic: starting subtest basic [ 243.574752] kms_addfb_basic: exiting, ret=0 [ 243.662526] gem_ctx_param_basic: executing [ 243.668855] gem_ctx_param_basic: starting subtest non-root-set-no-zeromap [ 243.671670] gem_ctx_param_basic: exiting, ret=0 [ 243.760596] gem_mmap_gtt: executing [ 243.767541] gem_mmap_gtt: starting subtest basic-small-copy-XY [ 243.806355] gem_mmap_gtt (6220): drop_caches: 3 [ 244.858182] [drm] RC6 on [ 245.620618] gem_mmap_gtt: exiting, ret=0 [ 245.733250] gem_tiled_fence_blits: executing [ 245.738741] gem_tiled_fence_blits: starting subtest basic [ 245.746353] gem_tiled_fence_blits: exiting, ret=0 [ 245.817568] gem_exec_basic: executing [ 245.823698] gem_exec_basic: starting subtest basic-bsd1 [ 245.824088] gem_exec_basic: exiting, ret=0 [ 245.954039] kms_flip: executing [ 245.960534] kms_flip: starting subtest basic-plain-flip [ 247.860122] [drm] RC6 on [ 257.865667] [drm] RC6 on [ 267.870956] [drm] RC6 on [ 276.596824] kms_flip: exiting, ret=0 [ 276.916694] gem_exec_basic: executing [ 276.922701] gem_exec_basic: starting subtest readonly-vebox [ 276.923218] gem_exec_basic: exiting, ret=0 [ 277.013617] gem_exec_basic: executing [ 277.021039] gem_exec_basic: starting subtest gtt-vebox [ 277.021876] gem_exec_basic: exiting, ret=0 [ 277.123335] gem_pread: executing [ 277.131150] gem_pread: starting subtest basic [ 277.575881] gem_pread: exiting, ret=0 [ 277.646294] gem_render_linear_blits: executing [ 277.652370] gem_render_linear_blits: starting subtest basic [ 277.662623] gem_render_linear_blits: exiting, ret=0 [ 277.751502] gem_exec_parse: executing [ 277.757994] gem_exec_parse: exiting, ret=0 [ 277.841617] gem_mmap_gtt: executing [ 277.849503] gem_mmap_gtt: starting subtest basic-small-copy [ 277.876290] gem_mmap_gtt (6248): drop_caches: 3 [ 279.627354] gem_mmap_gtt: exiting, ret=0 [ 279.764540] gem_render_tiled_blits: executing [ 279.772222] gem_render_tiled_blits: starting subtest basic [ 279.785048] gem_render_tiled_blits: exiting, ret=0 [ 279.854621] gem_tiled_pread_basic: executing [ 285.963123] gem_tiled_pread_basic: exiting, ret=0 [ 286.035969] gem_exec_nop: executing [ 286.041932] gem_exec_nop: starting subtest basic [ 296.107941] gem_exec_nop: exiting, ret=0 [ 296.776996] gem_exec_store: executing [ 296.785590] gem_exec_store: starting subtest basic-bsd2 [ 296.786353] gem_exec_store: exiting, ret=0 [ 296.854856] gem_mmap_gtt: executing [ 296.861283] gem_mmap_gtt: starting subtest basic-write-no-prefault [ 296.861423] Setting dangerous option prefault_disable - tainting kernel [ 296.959632] Setting dangerous option prefault_disable - tainting kernel [ 296.959786] gem_mmap_gtt: exiting, ret=0 [ 296.959908] Setting dangerous option prefault_disable - tainting kernel [ 297.038733] gem_exec_basic: executing [ 297.045100] gem_exec_basic: starting subtest readonly-default [ 297.045640] gem_exec_basic: exiting, ret=0 [ 297.115545] gem_mmap_gtt: executing [ 297.121922] gem_mmap_gtt: starting subtest basic-small-bo-tiledY [ 297.137249] gem_mmap_gtt (6269): drop_caches: 3 [ 297.279722] gem_mmap_gtt: exiting, ret=0 [ 297.413597] gem_ringfill: executing [ 297.419878] gem_ringfill: starting subtest basic-default-forked [ 297.862816] gem_ringfill: exiting, ret=0 [ 297.960498] kms_flip: executing [ 297.966408] kms_flip: starting subtest basic-flip-vs-modeset [ 299.888435] [drm] RC6 on [ 301.889372] [drm] RC6 on [ 303.890435] [drm] RC6 on [ 305.891504] [drm] RC6 on [ 307.884782] [drm] RC6 on [ 309.893774] [drm] RC6 on [ 311.894883] [drm] RC6 on [ 313.887902] [drm] RC6 on [ 315.896921] [drm] RC6 on [ 317.898148] [drm] RC6 on [ 319.899297] [drm] RC6 on [ 321.901237] [drm] RC6 on [ 323.901493] [drm] RC6 on [ 325.894576] [drm] RC6 on [ 327.903641] [drm] RC6 on [ 329.904671] [drm] RC6 on [ 331.905855] [drm] RC6 on [ 333.906857] [drm] RC6 on [ 335.908030] [drm] RC6 on [ 337.909011] [drm] RC6 on [ 339.910100] [drm] RC6 on [ 341.911271] [drm] RC6 on [ 343.912276] [drm] RC6 on [ 345.913483] [drm] RC6 on [ 347.914498] [drm] RC6 on [ 349.915527] [drm] RC6 on [ 351.916744] [drm] RC6 on [ 353.909841] [drm] RC6 on [ 355.918908] [drm] RC6 on [ 357.911916] [drm] RC6 on [ 358.652759] kms_flip: exiting, ret=0 [ 358.962263] gem_storedw_loop: executing [ 358.970813] gem_storedw_loop: starting subtest basic-bsd2 [ 359.920639] [drm] RC6 on [ 362.427441] gem_storedw_loop: exiting, ret=0 [ 362.907978] gem_exec_parse: executing [ 362.913736] gem_exec_parse: exiting, ret=0 [ 362.983100] gem_exec_basic: executing [ 362.989138] gem_exec_basic: starting subtest gtt-blt [ 362.989807] gem_exec_basic: exiting, ret=0 [ 363.063899] gem_exec_basic: executing [ 363.069753] gem_exec_basic: starting subtest basic-blt [ 363.070166] gem_exec_basic: exiting, ret=0 [ 363.156809] gem_mmap_gtt: executing [ 363.167203] gem_mmap_gtt: starting subtest basic-write [ 363.272073] gem_mmap_gtt: exiting, ret=0 [ 363.351052] gem_flink_basic: executing [ 363.356715] gem_flink_basic: starting subtest bad-open [ 363.356835] gem_flink_basic: exiting, ret=0 [ 363.432901] gem_storedw_loop: executing [ 363.438931] gem_storedw_loop: starting subtest basic-render [ 367.019991] gem_storedw_loop: exiting, ret=0 [ 367.385033] gem_ctx_param_basic: executing [ 367.391325] gem_ctx_param_basic: starting subtest invalid-size-get [ 367.391556] gem_ctx_param_basic: exiting, ret=0 [ 367.462991] kms_pipe_crc_basic: executing [ 367.472984] kms_pipe_crc_basic: starting subtest bad-source [ 367.473192] kms_pipe_crc_basic: exiting, ret=0 [ 367.554894] gem_exec_store: executing [ 367.560360] gem_exec_store: starting subtest basic-bsd [ 367.560865] gem_exec_store: exiting, ret=0 [ 367.629336] kms_force_connector_basic: executing [ 367.635010] kms_force_connector_basic: exiting, ret=0 [ 367.707864] gem_exec_basic: executing [ 367.714549] gem_exec_basic: starting subtest gtt-default [ 367.715189] gem_exec_basic: exiting, ret=0 [ 367.784502] prime_self_import: executing [ 367.784721] prime_self_import: starting subtest basic-llseek-size [ 367.791935] prime_self_import: exiting, ret=0 [ 367.869587] kms_addfb_basic: executing [ 367.875550] kms_addfb_basic: starting subtest basic-Y-tiled [ 367.876312] kms_addfb_basic: exiting, ret=0 [ 367.943799] gem_storedw_loop: executing [ 367.949551] gem_storedw_loop: starting subtest basic-blt [ 371.506771] gem_storedw_loop: exiting, ret=0 [ 371.945228] kms_pipe_crc_basic: executing [ 371.955473] kms_pipe_crc_basic: starting subtest read-crc-pipe-B [ 372.374589] kms_pipe_crc_basic: exiting, ret=0 [ 372.547545] gem_exec_basic: executing [ 372.552952] gem_exec_basic: starting subtest basic-default [ 372.553401] gem_exec_basic: exiting, ret=0 [ 372.629883] gem_mmap_gtt: executing [ 372.635941] gem_mmap_gtt: starting subtest basic-write-gtt [ 372.739713] gem_mmap_gtt: exiting, ret=0 [ 372.806522] kms_force_connector_basic: executing [ 372.813266] kms_force_connector_basic: exiting, ret=0 [ 372.906916] prime_self_import: executing [ 372.907204] prime_self_import: starting subtest basic-with_one_bo_two_files [ 372.914363] prime_self_import: exiting, ret=0 [ 372.984902] kms_flip: executing [ 372.990818] kms_flip: starting subtest basic-flip-vs-wf_vblank [ 373.928922] [drm] RC6 on [ 385.935350] [drm] RC6 on [ 396.941324] [drm] RC6 on [ 406.953693] kms_flip: exiting, ret=0 [ 407.312074] kms_addfb_basic: executing [ 407.317899] kms_addfb_basic: starting subtest bad-pitch-1024 [ 407.318481] kms_addfb_basic: exiting, ret=0 [ 407.387776] gem_basic: executing [ 407.393699] gem_basic: starting subtest create-fd-close [ 407.393918] gem_basic: exiting, ret=0 [ 407.469230] kms_pipe_crc_basic: executing [ 407.478191] kms_pipe_crc_basic: starting subtest nonblocking-crc-pipe-C [ 407.901882] kms_pipe_crc_basic: exiting, ret=0 [ 408.056049] drv_getparams_basic: executing [ 408.062248] drv_getparams_basic: starting subtest basic-eu-total [ 408.062368] drv_getparams_basic: exiting, ret=0 [ 408.140274] gem_mmap_gtt: executing [ 408.146770] gem_mmap_gtt: starting subtest basic-read [ 408.181415] gem_mmap_gtt: exiting, ret=0 [ 408.258972] gem_basic: executing [ 408.264714] gem_basic: starting subtest create-close [ 408.264985] gem_basic: exiting, ret=0 [ 408.353991] kms_addfb_basic: executing [ 408.360618] kms_addfb_basic: starting subtest clobberred-modifier [ 408.361585] kms_addfb_basic: exiting, ret=0 [ 408.452747] kms_sink_crc_basic: executing [ 408.462166] kms_sink_crc_basic: exiting, ret=77 [ 408.564724] gem_exec_whisper: executing [ 408.572165] gem_exec_whisper: starting subtest basic [ 408.943227] [drm] RC6 on [ 456.154969] gem_exec_whisper: exiting, ret=0 [ 456.315269] Console: switching to colour dummy device 80x25 [ 456.566261] [drm] Module unloaded [ 456.731959] [drm] Found 128MB of eLLC [ 456.732323] [drm] Memory usable by graphics device = 4096M [ 456.732342] [drm] Replacing VGA console driver [ 456.744763] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 456.744765] [drm] Driver supports precise vblank timestamp query. [ 456.756822] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 457.499132] [drm] failed to retrieve link info, disabling eDP [ 457.616096] fbcon: inteldrmfb (fb0) is primary device [ 457.640532] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 457.744681] Console: switching to colour frame buffer device 240x67 [ 457.750982] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 457.752775] acpi device:0f: registered as cooling_device10 [ 457.757230] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input19 [ 457.759826] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 457.850213] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 457.858438] gem_exec_store: executing [ 457.865784] gem_exec_store: starting subtest basic-default [ 457.866658] gem_exec_store: starting subtest basic-render [ 457.867394] gem_exec_store: starting subtest basic-bsd [ 457.867974] gem_exec_store: starting subtest basic-bsd1 [ 457.868719] gem_exec_store: starting subtest basic-bsd2 [ 457.869354] gem_exec_store: starting subtest basic-blt [ 457.869935] gem_exec_store: starting subtest basic-vebox [ 457.870628] gem_exec_store: exiting, ret=0 [ 457.880936] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 457.880939] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 457.880941] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 457.880943] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 457.880944] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 457.880946] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 457.899531] Console: switching to colour dummy device 80x25 [ 458.965674] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input20 [ 458.968088] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input21 [ 458.968789] [drm] RC6 on [ 458.969476] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input22 [ 458.971030] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input23 [ 458.972604] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input24 [ 460.179090] [drm] Module unloaded [ 460.240956] i915: unknown parameter 'inject_load_failure' ignored [ 460.261757] [drm] Found 128MB of eLLC [ 460.262273] [drm] Memory usable by graphics device = 4096M [ 460.262303] [drm] Replacing VGA console driver [ 460.274696] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 460.274698] [drm] Driver supports precise vblank timestamp query. [ 460.277325] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 461.029288] [drm] failed to retrieve link info, disabling eDP [ 461.145898] fbcon: inteldrmfb (fb0) is primary device [ 461.165450] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 461.270299] Console: switching to colour frame buffer device 240x67 [ 461.276184] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 461.278085] acpi device:0f: registered as cooling_device10 [ 461.283425] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input25 [ 461.285542] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 461.309317] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 461.326934] Console: switching to colour dummy device 80x25 [ 461.336228] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 461.336231] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 461.336233] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 461.336235] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 461.336236] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 461.336237] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 461.415560] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input26 [ 461.417655] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input27 [ 461.418886] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input28 [ 461.420150] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input29 [ 461.421340] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input30 [ 462.590509] [drm] RC6 on [ 462.630398] [drm] Module unloaded [ 462.693482] i915: unknown parameter 'inject_load_failure' ignored [ 462.711594] [drm] Found 128MB of eLLC [ 462.711881] [drm] Memory usable by graphics device = 4096M [ 462.711901] [drm] Replacing VGA console driver [ 462.723912] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 462.723915] [drm] Driver supports precise vblank timestamp query. [ 462.726579] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 463.476394] [drm] failed to retrieve link info, disabling eDP [ 463.590274] fbcon: inteldrmfb (fb0) is primary device [ 463.615112] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 463.714361] Console: switching to colour frame buffer device 240x67 [ 463.720200] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 463.722080] acpi device:0f: registered as cooling_device10 [ 463.726919] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input31 [ 463.728980] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 463.751622] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 463.768511] Console: switching to colour dummy device 80x25 [ 463.778151] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 463.778156] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 463.778159] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 463.778161] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 463.778163] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 463.778165] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 463.854939] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input32 [ 463.857617] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input33 [ 463.859025] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input34 [ 463.860439] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input35 [ 463.861685] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input36 [ 464.032161] [drm] RC6 on [ 464.075910] [drm] Module unloaded [ 464.139616] i915: unknown parameter 'inject_load_failure' ignored [ 464.158356] [drm] Found 128MB of eLLC [ 464.158644] [drm] Memory usable by graphics device = 4096M [ 464.158663] [drm] Replacing VGA console driver [ 464.170974] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 464.170977] [drm] Driver supports precise vblank timestamp query. [ 464.173995] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 464.926455] [drm] failed to retrieve link info, disabling eDP [ 465.044136] fbcon: inteldrmfb (fb0) is primary device [ 465.063310] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 465.169553] Console: switching to colour frame buffer device 240x67 [ 465.176100] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 465.178423] acpi device:0f: registered as cooling_device10 [ 465.183616] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input37 [ 465.185977] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 465.212344] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 465.228645] Console: switching to colour dummy device 80x25 [ 465.238851] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 465.238854] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 465.238856] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 465.238858] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 465.238859] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 465.238860] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 465.319262] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input38 [ 465.321976] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input39 [ 465.323763] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input40 [ 465.325414] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input41 [ 465.327093] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input42 [ 465.505303] [drm] RC6 on [ 465.554454] [drm] Module unloaded [ 465.621895] i915: unknown parameter 'inject_load_failure' ignored [ 465.642948] [drm] Found 128MB of eLLC [ 465.643367] [drm] Memory usable by graphics device = 4096M [ 465.643505] [drm] Replacing VGA console driver [ 465.656011] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 465.656014] [drm] Driver supports precise vblank timestamp query. [ 465.659057] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 466.410518] [drm] failed to retrieve link info, disabling eDP [ 466.528215] fbcon: inteldrmfb (fb0) is primary device [ 466.550491] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 466.651289] Console: switching to colour frame buffer device 240x67 [ 466.656542] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 466.658466] acpi device:0f: registered as cooling_device10 [ 466.662485] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input43 [ 466.664864] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 466.686818] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 466.703827] Console: switching to colour dummy device 80x25 [ 466.714818] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 466.714822] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 466.714825] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 466.714827] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 466.714829] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 466.714831] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 466.790570] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input44 [ 466.792508] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input45 [ 466.794002] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input46 [ 466.795293] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input47 [ 466.799133] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input48 [ 466.969818] [drm] RC6 on [ 467.016663] [drm] Module unloaded [ 467.088733] i915: unknown parameter 'inject_load_failure' ignored [ 467.106866] [drm] Found 128MB of eLLC [ 467.107156] [drm] Memory usable by graphics device = 4096M [ 467.107176] [drm] Replacing VGA console driver [ 467.117917] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 467.117920] [drm] Driver supports precise vblank timestamp query. [ 467.120564] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 467.872585] [drm] failed to retrieve link info, disabling eDP [ 467.988164] fbcon: inteldrmfb (fb0) is primary device [ 468.011375] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 468.111551] Console: switching to colour frame buffer device 240x67 [ 468.117358] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 468.119216] acpi device:0f: registered as cooling_device10 [ 468.124075] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input49 [ 468.126922] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 468.150580] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 468.169276] Console: switching to colour dummy device 80x25 [ 468.177553] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 468.177558] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 468.177561] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 468.177563] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 468.177564] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 468.177567] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 468.251168] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input50 [ 468.253765] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input51 [ 468.255466] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input52 [ 468.257263] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input53 [ 468.258715] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card0/input54 [ 468.427870] [drm] RC6 on [ 468.470878] [drm] Module unloaded [ 468.570497] [drm] Found 128MB of eLLC [ 468.570889] [drm] Memory usable by graphics device = 4096M [ 468.570909] [drm] Replacing VGA console driver [ 468.583141] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 468.583144] [drm] Driver supports precise vblank timestamp query. [ 468.586147] [drm] Finished loading i915/skl_dmc_ver1.bin (v1.26) [ 469.338645] [drm] failed to retrieve link info, disabling eDP [ 469.456469] fbcon: inteldrmfb (fb0) is primary device [ 469.475502] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 469.579685] Console: switching to colour frame buffer device 240x67 [ 469.585897] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 469.587856] acpi device:0f: registered as cooling_device10 [ 469.593474] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input55 [ 469.595735] [drm] Initialized i915 1.6.0 20160314 for 0000:00:02.0 on minor 0 [ 469.620902] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 469.635506] gem_exec_store: executing [ 469.640271] gem_exec_store: starting subtest basic-default [ 469.641090] gem_exec_store: starting subtest basic-render [ 469.641964] gem_exec_store: starting subtest basic-bsd [ 469.642473] gem_exec_store: starting subtest basic-bsd1 [ 469.643175] gem_exec_store: starting subtest basic-bsd2 [ 469.643785] gem_exec_store: starting subtest basic-blt [ 469.644284] gem_exec_store: starting subtest basic-vebox [ 469.644946] gem_exec_store: exiting, ret=0 [ 469.648650] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp [ 469.648654] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 469.648657] snd_hda_codec_realtek hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 469.648659] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 469.648661] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 469.648663] snd_hda_codec_realtek hdaudioC0D0: Mic=0x19 [ 469.790413] gem_ctx_create: executing [ 469.797423] gem_ctx_create: starting subtest basic [ 469.797806] gem_ctx_create: exiting, ret=0 [ 469.877772] gem_mmap_gtt: executing [ 469.883615] gem_mmap_gtt: starting subtest basic-small-bo-tiledX [ 469.945500] gem_mmap_gtt (6528): drop_caches: 3 [ 470.090702] gem_mmap_gtt: exiting, ret=0 [ 470.259475] gem_mmap_gtt: executing [ 470.267917] gem_mmap_gtt: starting subtest basic-read-no-prefault [ 470.268144] Setting dangerous option prefault_disable - tainting kernel [ 470.304135] Setting dangerous option prefault_disable - tainting kernel [ 470.304308] gem_mmap_gtt: exiting, ret=0 [ 470.304456] Setting dangerous option prefault_disable - tainting kernel [ 470.449561] kms_pipe_crc_basic: executing [ 470.460746] kms_pipe_crc_basic: starting subtest suspend-read-crc-pipe-C