Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[291.220846] (kms_setmode:5453) CRITICAL: Test assertion failure function test...
[291.220872] (kms_setmode:5453) CRITICAL: Failed assertion: status
[291.220897] (kms_setmode:5453) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[286.464854] (kms_setmode:5453) INFO:   Test id#1 CRTC count 1
[286.464864] (kms_setmode:5453) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[286.464872] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[286.465254] (kms_setmode:5453) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[286.465257] (kms_setmode:5453) ioctl_wrappers-DEBUG: Test requirement passed:...
[286.468183] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.473213] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.473215] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6920...
[286.486606] (kms_setmode:5453) intel_allocator_simple-DEBUG: Using simple all...
[286.491463] (kms_setmode:5453) intel_allocator_simple-DEBUG: <ial: 0x621f6923...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[293.137448] (kms_setmode:5457) CRITICAL: Test assertion failure function test...
[293.137471] (kms_setmode:5457) CRITICAL: Failed assertion: status
[293.137495] (kms_setmode:5457) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[288.384136] (kms_setmode:5457) INFO:   Test id#1 CRTC count 1
[288.384150] (kms_setmode:5457) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[288.384163] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[288.384546] (kms_setmode:5457) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[288.384550] (kms_setmode:5457) ioctl_wrappers-DEBUG: Test requirement passed:...
[288.387003] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.392198] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.392200] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
[288.405609] (kms_setmode:5457) intel_allocator_simple-DEBUG: Using simple all...
[288.410475] (kms_setmode:5457) intel_allocator_simple-DEBUG: <ial: 0x5991a641...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[300.430383] (kms_setmode:5477) CRITICAL: Test assertion failure function test...
[300.430405] (kms_setmode:5477) CRITICAL: Failed assertion: status
[300.430427] (kms_setmode:5477) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[295.642906] (kms_setmode:5477) INFO:   Test id#1 CRTC count 1
[295.642916] (kms_setmode:5477) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[295.642925] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[295.643268] (kms_setmode:5477) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[295.643271] (kms_setmode:5477) ioctl_wrappers-DEBUG: Test requirement passed:...
[295.645963] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.651640] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.651643] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09cc8...
[295.665793] (kms_setmode:5477) intel_allocator_simple-DEBUG: Using simple all...
[295.671007] (kms_setmode:5477) intel_allocator_simple-DEBUG: <ial: 0x60f09ccc...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[302.215870] (kms_setmode:5470) CRITICAL: Test assertion failure function test...
[302.215926] (kms_setmode:5470) CRITICAL: Failed assertion: status
[302.216385] (kms_setmode:5470) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[297.420923] (kms_setmode:5470) INFO:   Test id#1 CRTC count 1
[297.420949] (kms_setmode:5470) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[297.420958] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[297.421268] (kms_setmode:5470) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[297.421270] (kms_setmode:5470) ioctl_wrappers-DEBUG: Test requirement passed:...
[297.423540] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.428479] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.428481] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f4...
[297.441152] (kms_setmode:5470) intel_allocator_simple-DEBUG: Using simple all...
[297.446175] (kms_setmode:5470) intel_allocator_simple-DEBUG: <ial: 0x5fe207f8...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[292.791464] (kms_setmode:5436) CRITICAL: Test assertion failure function test...
[292.791491] (kms_setmode:5436) CRITICAL: Failed assertion: status
[292.791518] (kms_setmode:5436) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[287.963504] (kms_setmode:5436) INFO:   Test id#1 CRTC count 1
[287.963512] (kms_setmode:5436) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[287.963521] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[287.963979] (kms_setmode:5436) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[287.963981] (kms_setmode:5436) ioctl_wrappers-DEBUG: Test requirement passed:...
[287.966903] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.971979] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.971981] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e31...
[287.988462] (kms_setmode:5436) intel_allocator_simple-DEBUG: Using simple all...
[287.993380] (kms_setmode:5436) intel_allocator_simple-DEBUG: <ial: 0x62f28e35...
...

Starting subtest: basic
Starting dynamic subtest: pipe-A-eDP-1
[299.416436] (kms_setmode:5516) CRITICAL: Test assertion failure function test...
[299.416464] (kms_setmode:5516) CRITICAL: Failed assertion: status
[299.416491] (kms_setmode:5516) CRITICAL: VBlank timing test failed after 2 at...
Dynamic subtest pipe-A-eDP-1 failed.
**** DEBUG ****
[294.594679] (kms_setmode:5516) INFO:   Test id#1 CRTC count 1
[294.594687] (kms_setmode:5516) INFO:     CRTC[151] [Pipe A] Mode: 2880x1800@6...
[294.594695] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(width...
[294.595124] (kms_setmode:5516) igt_fb-DEBUG: igt_create_fb_with_bo_size(handl...
[294.595127] (kms_setmode:5516) ioctl_wrappers-DEBUG: Test requirement passed:...
[294.597238] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.602465] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.602467] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
[294.617706] (kms_setmode:5516) intel_allocator_simple-DEBUG: Using simple all...
[294.622679] (kms_setmode:5516) intel_allocator_simple-DEBUG: <ial: 0x5ca72f9b...
...