<6>[ 119.143325] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 119.147737] ata1.00: configured for UDMA/33
<6>[ 120.487322] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 120.490883] ata1.00: configured for UDMA/33
<6>[ 121.134939] Console: switching to colour dummy device 80x25
<7>[ 121.135814] [IGT] gem_mmap_gtt: executing
<6>[ 121.772027] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 121.775148] ata1.00: configured for UDMA/33
<7>[ 121.831082] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 121.844496] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 121.848134] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 121.851751] [IGT] gem_mmap_gtt: starting subtest pf-nonblock
<7>[ 121.889576] [IGT] gem_mmap_gtt: finished subtest pf-nonblock, SUCCESS
<7>[ 121.896280] [IGT] gem_mmap_gtt: exiting, ret=0
<7>[ 121.900042] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 121.948444] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 121.983508] Console: switching to colour frame buffer device 128x48
<6>[ 122.506164] Console: switching to colour dummy device 80x25
<7>[ 122.506830] [IGT] kms_frontbuffer_tracking: executing
<7>[ 122.524583] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 122.531210] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 122.534331] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 122.562661] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 122.564781] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 122.579299] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 122.581847] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 122.583480] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 122.586645] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 122.588193] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 122.588245] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 122.590682] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 122.592593] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 122.592649] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 122.596565] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 122.596718] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 122.596819] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 122.597072] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 122.597215] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 122.597355] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 122.616491] [IGT] kms_frontbuffer_tracking: exiting, ret=77
<7>[ 122.622600] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 122.633739] Console: switching to colour frame buffer device 128x48
<7>[ 122.694791] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<3>[ 122.722013] ata1.00: exception Emask 0x10 SAct 0x100 SErr 0x4050000 action 0xe frozen
<3>[ 122.724613] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 122.724758] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 122.724984] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 122.725168] ata1.00: cmd 61/08:40:c8:e0:9c/00:00:01:00:00/40 tag 8 ncq dma 4096 out
res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 122.725614] ata1.00: status: { DRDY }
<6>[ 122.725783] ata1: hard resetting link
<6>[ 123.451939] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 123.455084] ata1.00: configured for UDMA/33
<6>[ 123.455266] ata1: EH complete
<6>[ 123.939383] Console: switching to colour dummy device 80x25
<7>[ 123.940344] [IGT] syncobj_wait: executing
<7>[ 123.963341] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 123.968119] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 123.970698] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 123.988104] [IGT] syncobj_wait: starting subtest wait-delayed-signal
<7>[ 124.098232] [IGT] syncobj_wait: finished subtest wait-delayed-signal, SUCCESS
<7>[ 124.100410] [IGT] syncobj_wait: exiting, ret=0
<7>[ 124.104496] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 124.112885] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 124.135312] Console: switching to colour frame buffer device 128x48
<3>[ 124.223900] ata1.00: exception Emask 0x10 SAct 0x2000 SErr 0x4050000 action 0xe frozen
<3>[ 124.225959] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 124.226104] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 124.226235] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 124.226357] ata1.00: cmd 61/08:68:b8:80:fc/00:00:01:00:00/40 tag 13 ncq dma 4096 out
res 40/00:01:01:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 124.226658] ata1.00: status: { DRDY }
<6>[ 124.226850] ata1: hard resetting link
<6>[ 124.956818] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 124.959872] ata1.00: configured for UDMA/33
<6>[ 124.960050] ata1: EH complete
<6>[ 125.447587] Console: switching to colour dummy device 80x25
<7>[ 125.448591] [IGT] kms_frontbuffer_tracking: executing
<6>[ 125.805749] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 125.808972] ata1.00: configured for UDMA/33
<7>[ 125.843517] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 125.850497] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 125.853313] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 125.877126] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 125.877244] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 125.880564] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 125.885119] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 125.886796] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 125.888537] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 125.890079] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 125.890126] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 125.891745] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 125.894060] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 125.894119] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 125.896455] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 125.896933] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 125.897085] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 125.897228] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 125.897369] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 125.897503] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 125.911457] [IGT] kms_frontbuffer_tracking: exiting, ret=77
<7>[ 125.916325] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 125.927469] Console: switching to colour frame buffer device 128x48
<7>[ 125.982602] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 126.471332] Console: switching to colour dummy device 80x25
<7>[ 126.472905] [IGT] gem_ppgtt: executing
<6>[ 126.963647] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 126.966429] ata1.00: configured for UDMA/33
<7>[ 126.998404] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 127.005736] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 127.008815] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 127.012906] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 127.042731] [IGT] gem_ppgtt: starting subtest shrink-vs-evict-any
<7>[ 127.044387] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 127.052002] [IGT] gem_ppgtt: finished subtest shrink-vs-evict-any, SKIP
<7>[ 127.052895] [IGT] gem_ppgtt: exiting, ret=77
<7>[ 127.056095] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 127.076355] Console: switching to colour frame buffer device 128x48
<7>[ 127.130721] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<3>[ 127.163675] ata1.00: exception Emask 0x10 SAct 0x400 SErr 0x4050000 action 0xe frozen
<3>[ 127.163901] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 127.164040] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 127.164169] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 127.164290] ata1.00: cmd 61/08:50:d0:e0:9c/00:00:01:00:00/40 tag 10 ncq dma 4096 out
res 40/00:01:01:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 127.164767] ata1.00: status: { DRDY }
<6>[ 127.164928] ata1: hard resetting link
<6>[ 127.891609] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 127.895255] ata1.00: configured for UDMA/33
<6>[ 127.895576] ata1: EH complete
<6>[ 128.747537] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 128.751368] ata1.00: configured for UDMA/33
<6>[ 129.107205] Console: switching to colour dummy device 80x25
<7>[ 129.108055] [IGT] gem_ccs: executing
<6>[ 129.595476] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 129.598737] ata1.00: configured for UDMA/33
<7>[ 129.630974] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 129.636101] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 129.638501] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 129.641093] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 129.653936] [IGT] gem_ccs: exiting, ret=77
<7>[ 129.656999] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 129.666862] Console: switching to colour frame buffer device 128x48
<7>[ 129.731938] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 130.202288] Console: switching to colour dummy device 80x25
<7>[ 130.203586] [IGT] syncobj_wait: executing
<6>[ 130.574427] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 130.577284] ata1.00: configured for UDMA/33
<7>[ 130.609176] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 130.615496] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 130.618518] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 130.640859] [IGT] syncobj_wait: starting subtest wait-all-snapshot
<7>[ 130.729901] [IGT] syncobj_wait: finished subtest wait-all-snapshot, SUCCESS
<7>[ 130.731922] [IGT] syncobj_wait: exiting, ret=0
<7>[ 130.735434] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 130.750104] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 130.767258] Console: switching to colour frame buffer device 128x48
<6>[ 131.659370] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 131.662395] ata1.00: configured for UDMA/33
<6>[ 132.019146] Console: switching to colour dummy device 80x25
<7>[ 132.019991] [IGT] kms_chamelium_color: executing
<6>[ 132.509324] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 132.512098] ata1.00: configured for UDMA/33
<7>[ 132.550687] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 132.556073] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 132.558464] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 132.580122] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 132.580378] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 132.583807] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 132.585737] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 132.587186] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 132.588701] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 132.590067] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 132.590105] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 132.591542] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 132.593780] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 132.593835] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 132.596342] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 132.596491] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 132.596628] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 132.596763] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 132.596900] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 132.597037] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 132.599105] [IGT] kms_chamelium_color: exiting, ret=77
<7>[ 132.603864] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 132.616344] Console: switching to colour frame buffer device 128x48
<7>[ 132.683994] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 133.150361] Console: switching to colour dummy device 80x25
<7>[ 133.151013] [IGT] gem_render_copy: executing
<6>[ 133.653255] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 133.656103] ata1.00: configured for UDMA/33
<7>[ 133.686644] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 133.689687] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 133.697540] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 133.700595] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 133.781466] [IGT] gem_render_copy: starting subtest y-tiled-mc-ccs-to-vebox-yf-tiled
<7>[ 133.783440] [IGT] gem_render_copy: finished subtest y-tiled-mc-ccs-to-vebox-yf-tiled, SKIP
<7>[ 133.938950] [IGT] gem_render_copy: exiting, ret=77
<7>[ 133.948712] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 133.968579] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 133.986096] Console: switching to colour frame buffer device 128x48
<6>[ 134.590239] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 134.593338] ata1.00: configured for UDMA/33
<6>[ 135.069766] Console: switching to colour dummy device 80x25
<7>[ 135.070752] [IGT] i915_pm_freq_api: executing
<6>[ 135.563174] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 135.566188] ata1.00: configured for UDMA/33
<7>[ 135.590956] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 135.595761] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 135.598592] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 135.601451] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 135.615724] [IGT] i915_pm_freq_api: exiting, ret=77
<7>[ 135.619011] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 135.627966] Console: switching to colour frame buffer device 128x48
<7>[ 135.687037] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 136.165552] Console: switching to colour dummy device 80x25
<7>[ 136.166401] [IGT] gen3_mixed_blits: executing
<6>[ 136.524129] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 136.527352] ata1.00: configured for UDMA/33
<7>[ 136.555761] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 136.560787] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 136.564004] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 136.567207] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 136.582731] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6>[ 136.585693] gen3_mixed_blit (1471): drop_caches: 4
<7>[ 136.587533] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 138.160244] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<6>[ 142.416964] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 142.426506] ata1.00: configured for UDMA/33
<6>[ 143.246887] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 143.250395] ata1.00: configured for UDMA/33
<6>[ 144.354856] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 144.364157] ata1.00: configured for UDMA/33
<6>[ 158.199521] perf: interrupt took too long (3983 > 3948), lowering kernel.perf_event_max_sample_rate to 50000
<6>[ 170.146140] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 170.163148] ata1.00: configured for UDMA/33
<6>[ 173.394096] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 173.397327] ata1.00: configured for UDMA/33
<6>[ 174.522090] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 174.525303] ata1.00: configured for UDMA/33
<3>[ 175.789108] ata1.00: exception Emask 0x10 SAct 0x2000000 SErr 0x4050000 action 0xe frozen
<3>[ 175.789233] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 175.789314] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 175.789391] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 175.789461] ata1.00: cmd 60/30:c8:90:32:42/00:00:01:00:00/40 tag 25 ncq dma 24576 in
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 175.789639] ata1.00: status: { DRDY }
<6>[ 175.789709] ata1: hard resetting link
<6>[ 176.514113] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 176.516891] ata1.00: configured for UDMA/33
<6>[ 176.517665] ata1: EH complete
<3>[ 176.676111] ata1.00: exception Emask 0x10 SAct 0x800000 SErr 0x4050000 action 0xe frozen
<3>[ 176.676236] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 176.676317] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 176.676394] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 176.676465] ata1.00: cmd 60/40:b8:c0:3a:42/00:00:01:00:00/40 tag 23 ncq dma 32768 in
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 176.676643] ata1.00: status: { DRDY }
<6>[ 176.676712] ata1: hard resetting link
<6>[ 177.402156] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 177.405512] ata1.00: configured for UDMA/33
<6>[ 177.405679] ata1: EH complete
<6>[ 178.266144] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 178.269667] ata1.00: configured for UDMA/33
<3>[ 178.693118] ata1.00: exception Emask 0x10 SAct 0x1000 SErr 0x4050000 action 0xe frozen
<3>[ 178.693242] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 178.693323] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 178.693400] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 178.693470] ata1.00: cmd 60/30:60:90:3a:3c/00:00:01:00:00/40 tag 12 ncq dma 24576 in
res 40/00:ff:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 178.693648] ata1.00: status: { DRDY }
<6>[ 178.693717] ata1: hard resetting link
<6>[ 179.418095] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 179.421208] ata1.00: configured for UDMA/33
<6>[ 179.421439] ata1: EH complete
<6>[ 180.507146] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 180.510503] ata1.00: configured for UDMA/33
<6>[ 182.562097] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 182.565360] ata1.00: configured for UDMA/33
<6>[ 184.659092] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 184.662861] ata1.00: configured for UDMA/33
<3>[ 241.809567] ata1.00: exception Emask 0x40 SAct 0x1fff0800 SErr 0x8d0800 action 0x6 frozen
<3>[ 241.809694] ata1: SError: { HostInt PHYRdyChg CommWake 10B8B LinkSeq }
<3>[ 241.809782] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 241.809852] ata1.00: cmd 60/08:58:38:3f:be/00:00:0b:00:00/40 tag 11 ncq dma 4096 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.810026] ata1.00: status: { DRDY }
<3>[ 241.810087] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 241.810157] ata1.00: cmd 60/40:80:c0:47:c6/00:00:0b:00:00/40 tag 16 ncq dma 32768 in
res 40/00:00:00:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.810329] ata1.00: status: { DRDY }
<3>[ 241.810389] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.811822] ata1.00: cmd 61/08:88:08:a1:a1/00:00:16:00:00/40 tag 17 ncq dma 4096 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.812099] ata1.00: status: { DRDY }
<3>[ 241.812197] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.812313] ata1.00: cmd 61/08:90:f8:e0:9c/00:00:01:00:00/40 tag 18 ncq dma 4096 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.812638] ata1.00: status: { DRDY }
<3>[ 241.812740] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.812858] ata1.00: cmd 61/08:98:08:a0:a1/00:00:05:00:00/40 tag 19 ncq dma 4096 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.813132] ata1.00: status: { DRDY }
<3>[ 241.813225] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.813324] ata1.00: cmd 61/08:a0:a8:a2:a1/00:00:05:00:00/40 tag 20 ncq dma 4096 out
res 40/00:00:00:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.813654] ata1.00: status: { DRDY }
<3>[ 241.813759] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.813868] ata1.00: cmd 61/08:a8:e8:a2:a1/00:00:05:00:00/40 tag 21 ncq dma 4096 out
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.814140] ata1.00: status: { DRDY }
<3>[ 241.814245] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.814368] ata1.00: cmd 61/08:b0:08:b1:a5/00:00:05:00:00/40 tag 22 ncq dma 4096 out
res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.814748] ata1.00: status: { DRDY }
<3>[ 241.814849] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.814967] ata1.00: cmd 61/08:b8:10:a0:21/00:00:00:00:00/40 tag 23 ncq dma 4096 out
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.815229] ata1.00: status: { DRDY }
<3>[ 241.815318] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.815471] ata1.00: cmd 61/08:c0:30:a0:21/00:00:00:00:00/40 tag 24 ncq dma 4096 out
res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.815727] ata1.00: status: { DRDY }
<3>[ 241.815794] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.815865] ata1.00: cmd 61/08:c8:70:a0:61/00:00:01:00:00/40 tag 25 ncq dma 4096 out
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.816039] ata1.00: status: { DRDY }
<3>[ 241.816099] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.816169] ata1.00: cmd 61/08:d0:28:a7:61/00:00:01:00:00/40 tag 26 ncq dma 4096 out
res 40/00:01:01:4f:c2/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.816342] ata1.00: status: { DRDY }
<3>[ 241.816402] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 241.816517] ata1.00: cmd 61/08:d8:68:a8:61/00:00:01:00:00/40 tag 27 ncq dma 4096 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.816688] ata1.00: status: { DRDY }
<3>[ 241.816749] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 241.816818] ata1.00: cmd 60/08:e0:f0:42:68/00:00:01:00:00/40 tag 28 ncq dma 4096 in
res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x44 (timeout)
<3>[ 241.816991] ata1.00: status: { DRDY }
<6>[ 241.817060] ata1: hard resetting link
<6>[ 242.127720] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 242.130510] ata1.00: configured for UDMA/33
<6>[ 242.131004] sd 0:0:0:0: [sda] tag#28 FAILED Result: hostbyte=DID_TIME_OUT driverbyte=DRIVER_OK cmd_age=30s
<6>[ 242.131073] sd 0:0:0:0: [sda] tag#28 CDB: Read(10) 28 00 01 68 42 f0 00 00 08 00
<3>[ 242.131107] I/O error, dev sda, sector 23610096 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0
<6>[ 242.131322] ata1: EH complete
<3>[ 244.326503] ata1.00: exception Emask 0x10 SAct 0x20000000 SErr 0x4050000 action 0xe frozen
<3>[ 244.326629] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 244.326711] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 244.326789] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 244.326859] ata1.00: cmd 60/40:e8:80:5b:ca/00:00:0b:00:00/40 tag 29 ncq dma 32768 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 244.327037] ata1.00: status: { DRDY }
<6>[ 244.327106] ata1: hard resetting link
<6>[ 245.049479] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 245.053759] ata1.00: configured for UDMA/33
<6>[ 245.053929] ata1: EH complete
<3>[ 245.310507] ata1.00: exception Emask 0x10 SAct 0x1000 SErr 0x4050000 action 0xe frozen
<3>[ 245.310630] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 245.310712] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 245.310789] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 245.310859] ata1.00: cmd 60/40:60:c0:54:c2/00:00:0b:00:00/40 tag 12 ncq dma 32768 in
res 40/00:ff:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 245.311037] ata1.00: status: { DRDY }
<6>[ 245.311107] ata1: hard resetting link
<6>[ 246.033462] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 246.036697] ata1.00: configured for UDMA/33
<6>[ 246.036937] ata1: EH complete
<6>[ 250.897453] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 250.900450] ata1.00: configured for UDMA/33
<3>[ 251.318413] ata1.00: exception Emask 0x10 SAct 0x2 SErr 0x4050000 action 0xe frozen
<3>[ 251.318535] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 251.318616] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 251.318694] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 251.318763] ata1.00: cmd 60/40:08:c0:54:be/00:00:0b:00:00/40 tag 1 ncq dma 32768 in
res 40/00:ff:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 251.318941] ata1.00: status: { DRDY }
<6>[ 251.319011] ata1: hard resetting link
<6>[ 252.041421] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 252.044600] ata1.00: configured for UDMA/33
<6>[ 252.044835] ata1: EH complete
<6>[ 253.113405] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 253.118623] ata1.00: configured for UDMA/33
<6>[ 253.970638] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 253.975633] ata1.00: configured for UDMA/33
<6>[ 254.849339] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 254.852976] ata1.00: configured for UDMA/33
<3>[ 255.223349] ata1.00: exception Emask 0x10 SAct 0x10000000 SErr 0x4050000 action 0xe frozen
<3>[ 255.223474] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 255.223555] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 255.223633] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 255.223703] ata1.00: cmd 60/40:e0:c0:55:b6/00:00:0b:00:00/40 tag 28 ncq dma 32768 in
res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 255.223882] ata1.00: status: { DRDY }
<6>[ 255.223952] ata1: hard resetting link
<6>[ 255.945320] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 255.948268] ata1.00: configured for UDMA/33
<6>[ 255.948451] ata1: EH complete
<6>[ 258.492313] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 258.495778] ata1.00: configured for UDMA/33
<6>[ 259.354300] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 259.357881] ata1.00: configured for UDMA/33
<6>[ 261.529252] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 261.532450] ata1.00: configured for UDMA/33
<6>[ 263.241227] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 263.244743] ata1.00: configured for UDMA/33
<7>[ 266.062151] [IGT] gen3_mixed_blits: exiting, ret=0
<7>[ 266.097612] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 266.108571] Console: switching to colour frame buffer device 128x48
<7>[ 266.794704] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 267.931642] Console: switching to colour dummy device 80x25
<7>[ 267.934156] [IGT] kms_psr: executing
<7>[ 267.961663] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 267.969233] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 267.973613] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 268.042442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 268.042594] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 268.059173] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 268.060947] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 268.062600] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 268.065240] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 268.066766] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 268.066801] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 268.068165] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 268.070377] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 268.070440] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 268.073311] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 268.073468] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 268.073607] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 268.073747] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 268.073885] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 268.074020] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 268.077306] [IGT] kms_psr: exiting, ret=77
<7>[ 268.084163] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 268.094818] Console: switching to colour frame buffer device 128x48
<7>[ 268.175434] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 268.666932] Console: switching to colour dummy device 80x25
<7>[ 268.668444] [IGT] kms_atomic_transition: executing
<6>[ 269.164130] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 269.166977] ata1.00: configured for UDMA/33
<7>[ 269.205137] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 269.213707] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 269.217138] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 269.242224] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 269.242385] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 269.259188] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 269.263308] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 269.264937] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 269.271249] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 269.272687] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 269.272724] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 269.274258] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 269.276529] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 269.276593] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 269.278941] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 269.279161] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 269.279305] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 269.279446] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 269.279585] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 269.279724] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 269.281280] [IGT] kms_atomic_transition: exiting, ret=77
<7>[ 269.287424] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 269.299498] Console: switching to colour frame buffer device 128x48
<7>[ 269.375648] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<3>[ 269.401180] ata1.00: exception Emask 0x10 SAct 0x40 SErr 0x4050000 action 0xe frozen
<3>[ 269.401490] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 269.401699] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 269.401897] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 269.402211] ata1.00: cmd 61/08:30:18:e1:9c/00:00:01:00:00/40 tag 6 ncq dma 4096 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 269.402716] ata1.00: status: { DRDY }
<6>[ 269.402890] ata1: hard resetting link
<6>[ 270.129138] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 270.131915] ata1.00: configured for UDMA/33
<6>[ 270.132172] ata1: EH complete
<6>[ 270.634729] Console: switching to colour dummy device 80x25
<7>[ 270.635726] [IGT] core_setmaster: executing
<6>[ 272.415060] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 272.419381] ata1.00: configured for UDMA/33
<6>[ 286.822154] perf: interrupt took too long (4988 > 4978), lowering kernel.perf_event_max_sample_rate to 40000
<6>[ 287.530844] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 287.534898] ata1.00: configured for UDMA/33
<6>[ 293.118471] [drm] amdgpu kernel modesetting enabled.
<7>[ 293.119609] [drm:amdgpu_acpi_detect [amdgpu]] No matching acpi device found for AMD3000
<6>[ 293.130438] amdgpu: Virtual CRAT table created for CPU
<6>[ 293.136479] amdgpu: Topology: Add CPU node
<6>[ 293.173369] Console: switching to colour frame buffer device 128x48
<6>[ 293.525088] snd_hda_intel 0000:00:1b.0: enabling device (0004 -> 0006)
<6>[ 293.863115] snd_hda_codec_realtek hdaudioC0D2: autoconfig for ALC662 rev1: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:line
<6>[ 293.863149] snd_hda_codec_realtek hdaudioC0D2: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
<6>[ 293.863167] snd_hda_codec_realtek hdaudioC0D2: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
<6>[ 293.863183] snd_hda_codec_realtek hdaudioC0D2: mono: mono_out=0x0
<6>[ 293.863198] snd_hda_codec_realtek hdaudioC0D2: dig-out=0x1e/0x0
<6>[ 293.863213] snd_hda_codec_realtek hdaudioC0D2: inputs:
<6>[ 293.863234] snd_hda_codec_realtek hdaudioC0D2: Rear Mic=0x18
<6>[ 293.863256] snd_hda_codec_realtek hdaudioC0D2: Front Mic=0x19
<6>[ 293.863273] snd_hda_codec_realtek hdaudioC0D2: Line=0x1a
<6>[ 294.087000] input: HDA Intel Rear Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input2
<6>[ 294.105533] input: HDA Intel Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input3
<6>[ 294.116931] input: HDA Intel Line as /devices/pci0000:00/0000:00:1b.0/sound/card0/input4
<6>[ 294.314905] input: HDA Intel Line Out as /devices/pci0000:00/0000:00:1b.0/sound/card0/input5
<6>[ 294.327927] input: HDA Intel Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input6
<6>[ 294.779720] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 294.783050] ata1.00: configured for UDMA/33
<6>[ 295.632727] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 295.637282] ata1.00: configured for UDMA/33
<5>[ 296.072528] Setting dangerous option force_probe - tainting kernel
<6>[ 296.429761] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 296.432713] ata1.00: configured for UDMA/33
<7>[ 296.717718] [IGT] core_setmaster: starting subtest master-drop-set-root
<7>[ 296.720842] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 296.741545] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7>[ 296.744849] [IGT] core_setmaster: finished subtest master-drop-set-root, SUCCESS
<7>[ 296.745501] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 296.758322] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<7>[ 296.760097] [IGT] core_setmaster: exiting, ret=0
<6>[ 297.680725] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 297.683666] ata1.00: configured for UDMA/33
<6>[ 298.480663] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 298.483682] ata1.00: configured for UDMA/33
<6>[ 298.847741] Console: switching to colour dummy device 80x25
<7>[ 298.848406] [IGT] kms_frontbuffer_tracking: executing
<7>[ 298.868679] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 298.873930] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 298.876326] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 298.895162] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 298.895282] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 298.910706] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 298.913104] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 298.914657] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 298.920101] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 298.921794] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 298.921847] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 298.925200] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 298.931315] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 298.931377] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 298.933801] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 298.933954] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 298.934139] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 298.934290] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 298.934434] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 298.934641] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 298.959725] [IGT] kms_frontbuffer_tracking: exiting, ret=77
<7>[ 298.966021] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 298.984510] Console: switching to colour frame buffer device 128x48
<7>[ 299.092505] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<3>[ 299.130035] ata1.00: exception Emask 0x10 SAct 0x200 SErr 0x4050000 action 0xe frozen
<3>[ 299.130359] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 299.130684] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 299.130934] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 299.131121] ata1.00: cmd 61/08:48:38:e1:9c/00:00:01:00:00/40 tag 9 ncq dma 4096 out
res 40/00:01:00:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 299.131656] ata1.00: status: { DRDY }
<6>[ 299.131835] ata1: hard resetting link
<6>[ 299.858666] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 299.861411] ata1.00: configured for UDMA/33
<6>[ 299.861645] ata1: EH complete
<6>[ 300.729657] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 300.732969] ata1.00: configured for UDMA/33
<6>[ 301.128459] Console: switching to colour dummy device 80x25
<7>[ 301.131087] [IGT] kms_legacy_colorkey: executing
<6>[ 301.560591] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 301.563610] ata1.00: configured for UDMA/33
<7>[ 301.592865] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 301.599592] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 301.602845] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 301.628392] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 301.628660] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 301.645608] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 301.647367] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 301.648947] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 301.650788] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 301.652355] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 301.652407] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 301.653992] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 301.656270] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 301.656327] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 301.658758] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 301.658908] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 301.659052] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 301.659195] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 301.659338] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 301.659582] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 301.660565] [IGT] kms_legacy_colorkey: starting subtest basic
<7>[ 301.661408] [IGT] kms_legacy_colorkey: starting dynamic subtest pipe-A
<7>[ 301.662640] [IGT] kms_legacy_colorkey: finished subtest pipe-A, SUCCESS
<7>[ 301.663448] [IGT] kms_legacy_colorkey: starting dynamic subtest pipe-B
<7>[ 301.664675] [IGT] kms_legacy_colorkey: finished subtest pipe-B, SUCCESS
<7>[ 301.665685] [IGT] kms_legacy_colorkey: finished subtest basic, SUCCESS
<7>[ 301.667911] [IGT] kms_legacy_colorkey: exiting, ret=0
<7>[ 301.672854] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 301.694342] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 301.710966] Console: switching to colour frame buffer device 128x48
<3>[ 301.973577] ata1.00: exception Emask 0x10 SAct 0x402 SErr 0x4050000 action 0xe frozen
<3>[ 301.973804] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 301.973945] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 301.974076] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 301.974198] ata1.00: cmd 61/08:08:50:71:30/00:00:00:00:00/40 tag 1 ncq dma 4096 out
res 40/00:ff:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 301.974611] ata1.00: status: { DRDY }
<3>[ 301.974764] ata1.00: failed command: READ FPDMA QUEUED
<3>[ 301.974943] ata1.00: cmd 60/20:50:80:39:a6/00:00:05:00:00/40 tag 10 ncq dma 16384 in
res 40/00:01:01:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 301.975393] ata1.00: status: { DRDY }
<6>[ 301.975640] ata1: hard resetting link
<6>[ 302.704523] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 302.707520] ata1.00: configured for UDMA/33
<6>[ 302.707765] sd 0:0:0:0: [sda] tag#10 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=0s
<6>[ 302.707832] sd 0:0:0:0: [sda] tag#10 Sense Key : Illegal Request [current]
<6>[ 302.707886] sd 0:0:0:0: [sda] tag#10 Add. Sense: Unaligned write command
<6>[ 302.707940] sd 0:0:0:0: [sda] tag#10 CDB: Read(10) 28 00 05 a6 39 80 00 00 20 00
<3>[ 302.707974] I/O error, dev sda, sector 94779776 op 0x0:(READ) flags 0x80700 phys_seg 4 prio class 0
<6>[ 302.711919] ata1: EH complete
<6>[ 303.568511] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 303.571667] ata1.00: configured for UDMA/33
<6>[ 303.583212] Console: switching to colour dummy device 80x25
<7>[ 303.584243] [IGT] gem_sync: executing
<7>[ 303.611232] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 303.616463] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 303.618741] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 303.621709] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 303.710575] [IGT] gem_sync: starting subtest basic-all
<7>[ 303.713465] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 305.898192] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 305.906905] [IGT] gem_sync: finished subtest basic-all, SUCCESS
<7>[ 306.055179] [IGT] gem_sync: exiting, ret=0
<7>[ 306.058040] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 306.064621] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 306.079959] Console: switching to colour frame buffer device 128x48
<6>[ 306.872305] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 306.875528] ata1.00: configured for UDMA/33
<6>[ 307.364713] Console: switching to colour dummy device 80x25
<7>[ 307.366551] [IGT] kms_plane_scaling: executing
<6>[ 307.864272] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 307.867253] ata1.00: configured for UDMA/33
<7>[ 307.892332] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 307.899991] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 307.903397] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 307.926694] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 307.926812] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 307.930686] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 307.932436] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 307.933667] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 307.935422] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 307.937021] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 307.937073] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 307.938671] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 307.940891] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 307.940946] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 307.943374] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 307.943520] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 307.943661] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 307.943803] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 307.943944] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 307.944085] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 307.945971] [IGT] kms_plane_scaling: exiting, ret=77
<7>[ 307.950093] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 307.961437] Console: switching to colour frame buffer device 128x48
<7>[ 308.030375] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 308.760204] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 308.763196] ata1.00: configured for UDMA/33
<3>[ 308.866221] ata1.00: exception Emask 0x10 SAct 0x20000 SErr 0x4050000 action 0xe frozen
<3>[ 308.866458] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 308.866598] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 308.866728] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 308.866848] ata1.00: cmd 61/40:88:d8:b2:f9/00:00:0d:00:00/40 tag 17 ncq dma 32768 out
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 308.867281] ata1.00: status: { DRDY }
<6>[ 308.867451] ata1: hard resetting link
<6>[ 309.592159] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 309.595155] ata1.00: configured for UDMA/33
<6>[ 309.595327] ata1: EH complete
<6>[ 310.024913] Console: switching to colour dummy device 80x25
<7>[ 310.025962] [IGT] kms_pm_dc: executing
<7>[ 310.046834] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 310.054166] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 310.056561] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6>[ 314.695906] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 314.699296] ata1.00: configured for UDMA/33
<6>[ 321.279626] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 321.284088] ata1.00: configured for UDMA/33
<7>[ 321.285355] [IGT] kms_pm_dc: exiting, ret=77
<7>[ 321.288287] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 321.298040] Console: switching to colour frame buffer device 128x48
<7>[ 321.354344] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 321.848775] Console: switching to colour dummy device 80x25
<7>[ 321.849439] [IGT] kms_ccs: executing
<6>[ 322.335533] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 322.338622] ata1.00: configured for UDMA/33
<7>[ 322.366661] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 322.371628] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 322.373982] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 322.377332] [IGT] kms_ccs: exiting, ret=77
<7>[ 322.379910] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6>[ 322.388112] Console: switching to colour frame buffer device 128x48
<7>[ 322.455342] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<3>[ 322.556539] ata1.00: exception Emask 0x10 SAct 0x8 SErr 0x4050000 action 0xe frozen
<3>[ 322.556761] ata1.00: irq_stat 0x00000040, connection status changed
<3>[ 322.556901] ata1: SError: { PHYRdyChg CommWake DevExch }
<3>[ 322.557029] ata1.00: failed command: WRITE FPDMA QUEUED
<3>[ 322.557150] ata1.00: cmd 61/40:18:78:b8:f9/00:00:0d:00:00/40 tag 3 ncq dma 32768 out
res 40/00:00:00:4f:c2/00:00:00:00:00/00 Emask 0x10 (ATA bus error)
<3>[ 322.557560] ata1.00: status: { DRDY }
<6>[ 322.557682] ata1: hard resetting link
<6>[ 323.279507] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 323.282519] ata1.00: configured for UDMA/33
<6>[ 323.282703] ata1: EH complete
<6>[ 323.713007] Console: switching to colour dummy device 80x25
<7>[ 323.714313] [IGT] kms_sequence: executing
<6>[ 324.183460] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 324.186278] ata1.00: configured for UDMA/33
<7>[ 324.212493] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 324.217604] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 324.220522] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 324.239713] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1]
<7>[ 324.239832] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=1
<7>[ 324.258712] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7>[ 324.260129] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 324.261500] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK on first message, retry
<7>[ 324.263802] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus vga] NAK for addr: 0050 w(1)
<7>[ 324.265331] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 324.265474] i915 0000:00:02.0: [drm:intel_crt_get_edid [i915]] CRT GMBUS EDID read failed, retry using GPIO bit-banging
<7>[ 324.266982] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus vga. force bit now 1
<7>[ 324.269125] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus vga
<7>[ 324.269182] i915 0000:00:02.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus vga. force bit now 0
<7>[ 324.271941] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:48:VGA-1] probed modes:
<7>[ 324.272084] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 324.272222] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7>[ 324.272453] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7>[ 324.272601] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7>[ 324.272743] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7>[ 324.273681] [IGT] kms_sequence: starting subtest get-idle
<7>[ 324.278642] [IGT] kms_sequence: starting dynamic subtest pipe-A-VGA-1
<7>[ 324.283108] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:50]
<7>[ 324.359034] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:39:pipe A]
<7>[ 324.359200] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:48:VGA-1]
<7>[ 324.375004] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe B]
<7>[ 324.379420] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe A
<6>[ 325.183403] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 325.188600] ata1.00: configured for UDMA/33
<6>[ 325.999390] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 326.003002] ata1.00: configured for UDMA/33
<7>[ 326.534082] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:50] from all active usage due to RMFB ioctl
<7>[ 326.535989] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:32:plane A] because [FB:50] is removed
<7>[ 326.537640] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was enabled)
<7>[ 326.557075] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was disabled)
<7>[ 326.563443] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:39:pipe A]
<7>[ 326.564425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7>[ 326.566351] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7>[ 326.567976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7>[ 326.569579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7>[ 326.571166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7>[ 326.572791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7>[ 326.574364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7>[ 326.575963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7>[ 326.577597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7>[ 326.579227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7>[ 326.580850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7>[ 326.582435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7>[ 326.584013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7>[ 326.585601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7>[ 326.587169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7>[ 326.588713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7>[ 326.590339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7>[ 326.591811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7>[ 326.593187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7>[ 326.594730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7>[ 326.596017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7>[ 326.597655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7>[ 326.599186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7>[ 326.600738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7>[ 326.602295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7>[ 326.603584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7>[ 326.605118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7>[ 326.606411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7>[ 326.607960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7>[ 326.609487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7>[ 326.611034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7>[ 326.612369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7>[ 326.613873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64960, found 0)
<7>[ 326.615217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64960, found 0)
<7>[ 326.616894] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in port_clock (expected 64960, found 0)
<7>[ 326.618296] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] fastset requirement not met, forcing full modeset
<7>[ 326.620175] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7>[ 326.621784] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7>[ 326.623177] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:39:pipe A] enable: no [modeset]
<7>[ 326.624801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane A] fb: [NOFB], visible: no
<7>[ 326.628493] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7>[ 326.651415] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was disabled)
<7>[ 326.652678] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7>[ 326.654041] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7>[ 326.656400] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:39:pipe A]
<7>[ 326.659786] [IGT] kms_sequence: finished subtest pipe-A-VGA-1, SUCCESS
<7>[ 326.664548] [IGT] kms_sequence: starting dynamic subtest pipe-B-VGA-1
<7>[ 326.666846] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:50]
<7>[ 326.676457] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7>[ 326.699013] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7>[ 326.700888] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:39:pipe A]
<7>[ 326.704132] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe B]
<7>[ 326.704426] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:48:VGA-1]
<7>[ 326.706720] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7>[ 326.707946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7>[ 326.709216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7>[ 326.710801] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7>[ 326.712422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7>[ 326.713911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7>[ 326.715514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7>[ 326.717029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7>[ 326.718510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7>[ 326.720064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7>[ 326.721464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7>[ 326.721970] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7>[ 326.723179] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7>[ 326.724782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7>[ 326.726365] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7>[ 326.727737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7>[ 326.729326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7>[ 326.730688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7>[ 326.732167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7>[ 326.733391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7>[ 326.734874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7>[ 326.736175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7>[ 326.737696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7>[ 326.739136] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7>[ 326.740441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7>[ 326.741930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7>[ 326.743169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7>[ 326.744725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7>[ 326.746017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7>[ 326.747477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7>[ 326.748717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7>[ 326.750207] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7>[ 326.751541] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7>[ 326.753023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in dpll_hw_state
<7>[ 326.754361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7>[ 326.755859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x0, dpll_md: 0x0, fp0: 0x0, fp1: 0x0
<7>[ 326.757094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7>[ 326.758621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x94080000, dpll_md: 0x0, fp0: 0x4000c9, fp1: 0x4000c9
<7>[ 326.759874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64960)
<7>[ 326.761354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64960)
<7>[ 326.762842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in port_clock (expected 0, found 64960)
<7>[ 326.764079] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe B] fastset requirement not met, forcing full modeset
<7>[ 326.765739] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:40:plane B] min cdclk (64960 kHz) > [CRTC:47:pipe B] min cdclk (0 kHz)
<7>[ 326.767019] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7>[ 326.768458] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7>[ 326.769743] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe B] enable: yes [modeset]
<7>[ 326.771192] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7>[ 326.772424] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7>[ 326.773973] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7>[ 326.775333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7>[ 326.776851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7>[ 326.778171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7>[ 326.779694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7>[ 326.780975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7>[ 326.782404] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7>[ 326.783770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 326.785038] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 326.786450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7>[ 326.787782] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64960 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 326.789008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7>[ 326.790381] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64960, pipe src: 1024x768+0+0, pixel rate 64960
<7>[ 326.791666] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7>[ 326.792997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
<7>[ 326.794323] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7>[ 326.795663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x94080000, dpll_md: 0x0, fp0: 0x4000c9, fp1: 0x4000c9
<7>[ 326.796937] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7>[ 326.798294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7>[ 326.799605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:40:plane B] fb: [FB:50] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7>[ 326.800960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7>[ 326.802288] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7>[ 326.804180] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7>[ 326.805506] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7>[ 326.807619] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 24
<7>[ 326.808852] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 488
<7>[ 326.810073] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW1 register is f4030f0f
<7>[ 326.811448] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 21
<7>[ 326.812714] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 491
<7>[ 326.814046] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 146
<7>[ 326.815367] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 366
<7>[ 326.816728] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 143
<7>[ 326.818000] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 369
<7>[ 326.819332] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW3 register is 3f3f016e
<7>[ 326.820645] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is enabled (was disabled)
<7>[ 326.821991] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7>[ 326.840278] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7>[ 326.841568] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe B]
<7>[ 326.845626] i915 0000:00:02.0: [drm:i915_fifo_underrun_reset_write [i915]] Re-arming FIFO underruns on pipe B
<6>[ 327.456338] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 327.459930] ata1.00: configured for UDMA/33
<7>[ 329.027323] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:50] from all active usage due to RMFB ioctl
<7>[ 329.027609] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:40:plane B] because [FB:50] is removed
<7>[ 329.028021] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was enabled)
<7>[ 329.058093] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was disabled)
<7>[ 329.062895] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe B]
<7>[ 329.063926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7>[ 329.065615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7>[ 329.067228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7>[ 329.068824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7>[ 329.070456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7>[ 329.072031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7>[ 329.073619] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7>[ 329.075219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7>[ 329.076813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7>[ 329.078428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7>[ 329.080031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7>[ 329.081611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7>[ 329.083229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7>[ 329.084803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7>[ 329.086378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7>[ 329.087918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7>[ 329.089433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7>[ 329.090972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7>[ 329.092470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7>[ 329.094015] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7>[ 329.095673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7>[ 329.097255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7>[ 329.098833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7>[ 329.100396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7>[ 329.101987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7>[ 329.103572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7>[ 329.105197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7>[ 329.106789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7>[ 329.108316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7>[ 329.109896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7>[ 329.111423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7>[ 329.112976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7>[ 329.114550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64960, found 0)
<7>[ 329.116185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64960, found 0)
<7>[ 329.117804] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in port_clock (expected 64960, found 0)
<7>[ 329.119300] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe B] fastset requirement not met, forcing full modeset
<7>[ 329.121094] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7>[ 329.122633] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7>[ 329.124260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe B] enable: no [modeset]
<7>[ 329.125828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:40:plane B] fb: [NOFB], visible: no
<7>[ 329.130615] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7>[ 329.151313] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was disabled)
<7>[ 329.152888] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7>[ 329.154674] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7>[ 329.156416] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe B]
<7>[ 329.159770] [IGT] kms_sequence: finished subtest pipe-B-VGA-1, SUCCESS
<7>[ 329.160858] [IGT] kms_sequence: finished subtest get-idle, SUCCESS
<7>[ 329.162930] [IGT] kms_sequence: exiting, ret=0
<7>[ 329.169061] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 329.174470] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7>[ 329.176114] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7>[ 329.176613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7>[ 329.178270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7>[ 329.179868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7>[ 329.181438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7>[ 329.183223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7>[ 329.184802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7>[ 329.186464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7>[ 329.188214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7>[ 329.189810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7>[ 329.191442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7>[ 329.192952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7>[ 329.194309] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7>[ 329.195754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7>[ 329.197101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7>[ 329.198568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7>[ 329.199925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7>[ 329.201352] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7>[ 329.202653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7>[ 329.204100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7>[ 329.205476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7>[ 329.206849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7>[ 329.208254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7>[ 329.209708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7>[ 329.211001] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7>[ 329.212420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7>[ 329.213845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7>[ 329.215262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7>[ 329.216665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7>[ 329.218108] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7>[ 329.219422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7>[ 329.220789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7>[ 329.222161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7>[ 329.223737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64960)
<7>[ 329.225371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64960)
<7>[ 329.226909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in port_clock (expected 0, found 64960)
<7>[ 329.228240] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] fastset requirement not met, forcing full modeset
<7>[ 329.229779] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane A] min cdclk (64960 kHz) > [CRTC:39:pipe A] min cdclk (0 kHz)
<7>[ 329.231078] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7>[ 329.232446] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7>[ 329.233766] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:39:pipe A] enable: yes [modeset]
<7>[ 329.235083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7>[ 329.236350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7>[ 329.237808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7>[ 329.239065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7>[ 329.240423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7>[ 329.241718] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7>[ 329.243023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7>[ 329.244268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7>[ 329.245673] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7>[ 329.246930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 329.248308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 329.249600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7>[ 329.250926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64960 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7>[ 329.252253] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7>[ 329.253663] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64960, pipe src: 1024x768+0+0, pixel rate 64960
<7>[ 329.254926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7>[ 329.256269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
<7>[ 329.257564] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7>[ 329.258887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x94080000, dpll_md: 0x0, fp0: 0x4000c9, fp1: 0x4000c9
<7>[ 329.260184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7>[ 329.261568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7>[ 329.262816] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane A] fb: [FB:52] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7>[ 329.264180] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7>[ 329.265496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7>[ 329.266879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:cursor A] fb: [NOFB], visible: no
<7>[ 329.268728] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7>[ 329.270272] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7>[ 329.272505] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 24
<7>[ 329.273793] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 488
<7>[ 329.275029] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW1 register is f4030f0f
<7>[ 329.276373] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 21
<7>[ 329.277682] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 491
<7>[ 329.279011] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 146
<7>[ 329.280239] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 366
<7>[ 329.281635] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 143
<7>[ 329.282889] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: 369
<7>[ 329.284230] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW3 register is 3f3f016e
<7>[ 329.285543] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is enabled (was disabled)
<7>[ 329.286874] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7>[ 329.305035] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7>[ 329.306386] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:39:pipe A]
<7>[ 329.308585] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 329.322479] Console: switching to colour frame buffer device 128x48
<6>[ 329.725209] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 329.728022] ata1.00: configured for UDMA/33
<6>[ 330.254606] Console: switching to colour dummy device 80x25
<7>[ 330.255512] [IGT] gem_exec_suspend: executing
<6>[ 330.743184] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 330.746029] ata1.00: configured for UDMA/33
<7>[ 330.777672] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 330.786759] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 330.790307] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 330.793432] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7>[ 330.872594] [IGT] gem_exec_suspend: starting subtest basic-S4-devices
<7>[ 330.873719] [IGT] gem_exec_suspend: starting dynamic subtest smem
<7>[ 330.885233] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 332.542359] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 332.557789] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6>[ 333.358170] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
<6>[ 333.362818] ata1.00: configured for UDMA/33
<6>[ 334.269307] PM: hibernation: hibernation entry
�������������������������������������������