igt@gem_exec_big@single on shard-tglu@IGTPW

Detail	Value
Duration	4.26 seconds
Hostname	shard-tglu-10
Igt-Version	IGT-Version: 2.3-gc2bd549fb (x86_64) (Linux: 7.0.0-rc6-CI_DRM_18253-g8d31b64e763b+ x86_64)
Out	Using IGT_SRANDOM=1774953508 for randomisation Opened device: /dev/dri/card0 Starting subtest: single Submitting a 7414MiB batch, aperture size 268435456MiB Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 [gem_execbuf+0x48] #2 ../tests/intel/gem_exec_big.c:195 execN() #3 ../tests/intel/gem_exec_big.c:317 __igt_unique____real_main321() #4 ../tests/intel/gem_exec_big.c:321 main() #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() #7 [_start+0x25] Subtest single: FAIL (4.263s)
Err	Starting subtest: single (gem_exec_big:2563) ioctl_wrappers-CRITICAL: Test assertion failure function gem_execbuf, file ../lib/ioctl_wrappers.c:643: (gem_exec_big:2563) ioctl_wrappers-CRITICAL: Failed assertion: __gem_execbuf(fd, execbuf) == 0 (gem_exec_big:2563) ioctl_wrappers-CRITICAL: error: -12 != 0 Subtest single failed. ** DEBUG (gem_exec_big:2563) drmtest-DEBUG: Force option used: Using driver i915 (gem_exec_big:2563) drmtest-DEBUG: Opened previously opened device: /dev/dri/card0 (gem_exec_big:2563) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_exec_big:2563) INFO: Submitting a 7414MiB batch, aperture size 268435456MiB (gem_exec_big:2563) igt_os-DEBUG: Checking 1 surfaces of size 7774142464 bytes (total 7774150656) against RAM (gem_exec_big:2563) drmtest-DEBUG: Force option used: Using driver i915 (gem_exec_big:2563) drmtest-DEBUG: Opened previously opened device: /dev/dri/card0 (gem_exec_big:2563) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_exec_big:2563) igt_os-DEBUG: Test requirement passed: sufficient_memory (gem_exec_big:2563) ioctl_wrappers-CRITICAL: Test assertion failure function gem_execbuf, file ../lib/ioctl_wrappers.c:643: (gem_exec_big:2563) ioctl_wrappers-CRITICAL: Failed assertion: __gem_execbuf(fd, execbuf) == 0 (gem_exec_big:2563) ioctl_wrappers-CRITICAL: error: -12 != 0 (gem_exec_big:2563) igt_core-INFO: Stack trace: (gem_exec_big:2563) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (gem_exec_big:2563) igt_core-INFO: #1 [gem_execbuf+0x48] (gem_exec_big:2563) igt_core-INFO: #2 ../tests/intel/gem_exec_big.c:195 execN() (gem_exec_big:2563) igt_core-INFO: #3 ../tests/intel/gem_exec_big.c:317 __igt_unique____real_main321() (gem_exec_big:2563) igt_core-INFO: #4 ../tests/intel/gem_exec_big.c:321 main() (gem_exec_big:2563) igt_core-INFO: #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main() (gem_exec_big:2563) igt_core-INFO: #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34() (gem_exec_big:2563) igt_core-INFO: #7 [_start+0x25] END ** Subtest single: FAIL (4.263s)
Dmesg Scroll to first warning	<7> [307.084252] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810 <7> [307.088838] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL <7> [307.088879] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled <6> [307.110314] Console: switching to colour dummy device 80x25 <6> [307.110765] [IGT] gem_exec_big: executing <7> [307.113337] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [307.113994] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [307.114311] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [307.114775] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <6> [307.119751] [IGT] gem_exec_big: starting subtest single <7> [307.120013] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <6> [307.120495] gem_exec_big (2563): drop_caches: 4 <7> [307.120573] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7> [307.529360] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7> [307.552397] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [307.553275] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7> [307.574308] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070] <7> [307.949315] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [307.949311] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [307.949369] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off <7> [307.949311] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4 <7> [307.949565] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded <7> [307.949748] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4 <4> [309.419523] ------------[ cut here ]------------ <3> [309.419690] UBSAN: shift-out-of-bounds in drivers/iommu/intel/iommu.h:1070:17 <3> [309.419781] shift exponent 64 is too large for 64-bit type 'long long unsigned int' <4> [309.419792] CPU: 5 UID: 0 PID: 2563 Comm: gem_exec_big Tainted: G S U L 7.0.0-rc6-CI_DRM_18253-g8d31b64e763b+ #1 PREEMPT(lazy) <4> [309.419795] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [L]=SOFTLOCKUP <4> [309.419795] Hardware name: Intel(R) Client Systems NUC11TNHi5/NUC11TNBi5, BIOS TNTGL357.0042.2020.1221.1743 12/21/2020 <4> [309.419796] Call Trace: <4> [309.419797] <TASK> <4> [309.419798] dump_stack_lvl+0x91/0xf0 <4> [309.419803] dump_stack+0x10/0x20 <4> [309.419805] __ubsan_handle_shift_out_of_bounds+0x133/0x2e0 <4> [309.419809] ? lock_acquire+0xc4/0x2f0 <4> [309.419812] ? cache_tag_flush_range+0x76/0x3d0 <4> [309.419818] qi_batch_add_dev_iotlb.part.0.cold+0x1e/0x35 <4> [309.419823] cache_tag_flush_range+0x236/0x3d0 <4> [309.419830] intel_iommu_tlb_sync+0x26/0x50 <4> [309.419832] iommu_unmap+0x72/0xa0 <4> [309.419838] iommu_map_nosync+0x158/0x340 <4> [309.419843] iommu_map_sg+0xd9/0x1d0 <4> [309.419847] iommu_dma_map_sg+0x267/0x630 <4> [309.419854] __dma_map_sg_attrs+0x1c6/0x2a0 <4> [309.419859] dma_map_sg_attrs+0xe/0x30 <4> [309.419861] i915_gem_gtt_prepare_pages+0x59/0x100 [i915] <4> [309.420039] shmem_get_pages+0x7a/0x2e0 [i915] <4> [309.420168] ____i915_gem_object_get_pages+0x3f/0x120 [i915] <4> [309.420291] __i915_gem_object_get_pages+0xa5/0x110 [i915] <4> [309.420421] i915_vma_get_pages+0xf9/0x300 [i915] <4> [309.420563] i915_vma_pin_ww+0xf5/0x1390 [i915] <4> [309.420710] eb_validate_vmas+0x209/0xd30 [i915] <4> [309.420841] ? eb_pin_engine+0x2f0/0x3b0 [i915] <4> [309.420971] i915_gem_do_execbuffer+0xda3/0x36e0 [i915] <4> [309.421105] ? lock_release+0xd0/0x2b0 <4> [309.421112] ? kernel_text_address+0x139/0x150 <4> [309.421125] ? __lock_acquire+0x43e/0x2790 <4> [309.421128] ? find_held_lock+0x31/0x90 <4> [309.421130] ? __create_object+0x68/0xc0 <4> [309.421138] ? find_held_lock+0x31/0x90 <4> [309.421140] ? __might_fault+0x53/0xb0 <4> [309.421150] i915_gem_execbuffer2_ioctl+0x169/0x320 [i915] <4> [309.421279] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4> [309.421406] drm_ioctl_kernel+0xb3/0x120 <4> [309.421411] drm_ioctl+0x2d4/0x5a0 <4> [309.421412] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915] <4> [309.421543] ? __update_load_avg_cfs_rq+0x2e9/0x670 <4> [309.421545] ? lock_is_held_type+0xa3/0x130 <4> [309.421552] __x64_sys_ioctl+0xa5/0x100 <4> [309.421556] x64_sys_call+0x1250/0x26e0 <4> [309.421560] do_syscall_64+0xdd/0x1440 <4> [309.421565] ? lock_acquire+0xc4/0x2f0 <4> [309.421568] ? find_held_lock+0x31/0x90 <4> [309.421570] ? ktime_get+0x23/0x110 <4> [309.421573] ? lock_release+0xd0/0x2b0 <4> [309.421577] ? seqcount_lockdep_reader_access.constprop.0+0x4d/0xc0 <4> [309.421580] ? ktime_get+0x23/0x110 <4> [309.421581] ? ktime_get+0x46/0x110 <4> [309.421583] ? lapic_next_deadline+0x26/0x50 <4> [309.421585] ? clockevents_program_event+0xb2/0x110 <4> [309.421589] ? tick_program_event+0x43/0xa0 <4> [309.421591] ? _raw_spin_unlock_irqrestore+0x27/0x80 <4> [309.421594] ? hrtimer_interrupt+0x13f/0x290 <4> [309.421597] ? trace_hardirqs_on_prepare+0xe1/0x100 <4> [309.421601] ? irqentry_exit+0x17d/0x780 <4> [309.421603] ? clear_bhb_loop+0x30/0x80 <4> [309.421607] entry_SYSCALL_64_after_hwframe+0x76/0x7e <4> [309.421608] RIP: 0033:0x7a8ef7524e1d <4> [309.421611] Code: 04 25 28 00 00 00 48 89 45 c8 31 c0 48 8d 45 10 c7 45 b0 10 00 00 00 48 89 45 b8 48 8d 45 d0 48 89 45 c0 b8 10 00 00 00 0f 05 <89> c2 3d 00 f0 ff ff 77 1a 48 8b 45 c8 64 48 2b 04 25 28 00 00 00 <4> [309.421612] RSP: 002b:00007ffed5128550 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 <4> [309.421614] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007a8ef7524e1d <4> [309.421615] RDX: 00007ffed5128650 RSI: 0000000040406469 RDI: 0000000000000005 <4> [309.421616] RBP: 00007ffed51285a0 R08: 0000000000000000 R09: 00007a8ef7a1b440 <4> [309.421616] R10: 00007a8ef77ee0f8 R11: 0000000000000246 R12: 00007ffed5128650 <4> [309.421617] R13: 0000000040406469 R14: 0000000000000005 R15: 00007ffed5128650 <4> [309.421624] </TASK> <4> [309.421625] ---[ end trace ]--- <4> [309.430021] i915 0000:00:02.0: Failed to DMA remap 1897984 pages <4> [310.362132] i915 0000:00:02.0: Failed to DMA remap 1897984 pages <4> [311.293773] i915 0000:00:02.0: Failed to DMA remap 1897984 pages <6> [311.383175] [IGT] gem_exec_big: finished subtest single, FAIL <6> [311.383957] [IGT] gem_exec_big: exiting, ret=98 <7> [311.384163] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [311.427719] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0 <6> [311.436023] Console: switching to colour frame buffer device 240x67

<7> [307.084252] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810

<7> [307.088838] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL

<7> [307.088879] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled

<6> [307.110314] Console: switching to colour dummy device 80x25

<6> [307.110765] [IGT] gem_exec_big: executing

<7> [307.113337] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [307.113994] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]

<7> [307.114311] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [307.114775] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<6> [307.119751] [IGT] gem_exec_big: starting subtest single

<7> [307.120013] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<6> [307.120495] gem_exec_big (2563): drop_caches: 4

<7> [307.120573] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]

<7> [307.529360] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]

<7> [307.552397] i915 0000:00:02.0: [drm:i915_gem_open [i915]]

<7> [307.553275] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]

<7> [307.574308] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]

<7> [307.949315] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [307.949311] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [307.949369] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off

<7> [307.949311] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4

<7> [307.949565] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded

<7> [307.949748] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4

<4> [309.419523] ------------[ cut here ]------------

<3> [309.419690] UBSAN: shift-out-of-bounds in drivers/iommu/intel/iommu.h:1070:17

<3> [309.419781] shift exponent 64 is too large for 64-bit type 'long long unsigned int'

<4> [309.419792] CPU: 5 UID: 0 PID: 2563 Comm: gem_exec_big Tainted: G S U L 7.0.0-rc6-CI_DRM_18253-g8d31b64e763b+ #1 PREEMPT(lazy)

<4> [309.419795] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [L]=SOFTLOCKUP

<4> [309.419795] Hardware name: Intel(R) Client Systems NUC11TNHi5/NUC11TNBi5, BIOS TNTGL357.0042.2020.1221.1743 12/21/2020

<4> [309.419805] __ubsan_handle_shift_out_of_bounds+0x133/0x2e0

<4> [309.419812] ? cache_tag_flush_range+0x76/0x3d0

<4> [309.419818] qi_batch_add_dev_iotlb.part.0.cold+0x1e/0x35

<4> [309.419823] cache_tag_flush_range+0x236/0x3d0

<4> [309.419830] intel_iommu_tlb_sync+0x26/0x50

<4> [309.419838] iommu_map_nosync+0x158/0x340

<4> [309.419847] iommu_dma_map_sg+0x267/0x630

<4> [309.419854] __dma_map_sg_attrs+0x1c6/0x2a0

<4> [309.419861] i915_gem_gtt_prepare_pages+0x59/0x100 [i915]

<4> [309.420039] shmem_get_pages+0x7a/0x2e0 [i915]

<4> [309.420168] ____i915_gem_object_get_pages+0x3f/0x120 [i915]

<4> [309.420291] __i915_gem_object_get_pages+0xa5/0x110 [i915]

<4> [309.420421] i915_vma_get_pages+0xf9/0x300 [i915]

<4> [309.420563] i915_vma_pin_ww+0xf5/0x1390 [i915]

<4> [309.420710] eb_validate_vmas+0x209/0xd30 [i915]

<4> [309.420841] ? eb_pin_engine+0x2f0/0x3b0 [i915]

<4> [309.420971] i915_gem_do_execbuffer+0xda3/0x36e0 [i915]

<4> [309.421112] ? kernel_text_address+0x139/0x150

<4> [309.421125] ? __lock_acquire+0x43e/0x2790

<4> [309.421128] ? find_held_lock+0x31/0x90

<4> [309.421130] ? __create_object+0x68/0xc0

<4> [309.421138] ? find_held_lock+0x31/0x90

<4> [309.421150] i915_gem_execbuffer2_ioctl+0x169/0x320 [i915]

<4> [309.421279] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915]

<4> [309.421406] drm_ioctl_kernel+0xb3/0x120

<4> [309.421412] ? __pfx_i915_gem_execbuffer2_ioctl+0x10/0x10 [i915]

<4> [309.421543] ? __update_load_avg_cfs_rq+0x2e9/0x670

<4> [309.421545] ? lock_is_held_type+0xa3/0x130

<4> [309.421552] __x64_sys_ioctl+0xa5/0x100

<4> [309.421556] x64_sys_call+0x1250/0x26e0

<4> [309.421568] ? find_held_lock+0x31/0x90

<4> [309.421577] ? seqcount_lockdep_reader_access.constprop.0+0x4d/0xc0

<4> [309.421583] ? lapic_next_deadline+0x26/0x50

<4> [309.421585] ? clockevents_program_event+0xb2/0x110

<4> [309.421589] ? tick_program_event+0x43/0xa0

<4> [309.421591] ? _raw_spin_unlock_irqrestore+0x27/0x80

<4> [309.421594] ? hrtimer_interrupt+0x13f/0x290

<4> [309.421597] ? trace_hardirqs_on_prepare+0xe1/0x100

<4> [309.421601] ? irqentry_exit+0x17d/0x780

<4> [309.421603] ? clear_bhb_loop+0x30/0x80

<4> [309.421607] entry_SYSCALL_64_after_hwframe+0x76/0x7e

<4> [309.421611] Code: 04 25 28 00 00 00 48 89 45 c8 31 c0 48 8d 45 10 c7 45 b0 10 00 00 00 48 89 45 b8 48 8d 45 d0 48 89 45 c0 b8 10 00 00 00 0f 05 <89> c2 3d 00 f0 ff ff 77 1a 48 8b 45 c8 64 48 2b 04 25 28 00 00 00

<4> [309.421612] RSP: 002b:00007ffed5128550 EFLAGS: 00000246 ORIG_RAX: 0000000000000010

<4> [309.421614] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007a8ef7524e1d

<4> [309.421615] RDX: 00007ffed5128650 RSI: 0000000040406469 RDI: 0000000000000005

<4> [309.421616] RBP: 00007ffed51285a0 R08: 0000000000000000 R09: 00007a8ef7a1b440

<4> [309.421616] R10: 00007a8ef77ee0f8 R11: 0000000000000246 R12: 00007ffed5128650

<4> [309.421617] R13: 0000000040406469 R14: 0000000000000005 R15: 00007ffed5128650

<4> [309.430021] i915 0000:00:02.0: Failed to DMA remap 1897984 pages

<4> [310.362132] i915 0000:00:02.0: Failed to DMA remap 1897984 pages

<4> [311.293773] i915 0000:00:02.0: Failed to DMA remap 1897984 pages

<6> [311.383175] [IGT] gem_exec_big: finished subtest single, FAIL

<6> [311.383957] [IGT] gem_exec_big: exiting, ret=98

<7> [311.384163] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]

<7> [311.427719] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0

<6> [311.436023] Console: switching to colour frame buffer device 240x67

Results for igt@gem_exec_big@single