Stdout	
IGT-Version: 1.20-gc0be331 (x86_64) (Linux: 4.15.0-rc4-drm-tip-ww51-commit-cd7e144+ x86_64)
Using monotonic timestamps
Beginning 2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1
  2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
  2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
[cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:11 2017
[cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:30 2017

2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1: PASSED

Beginning 2x-vblank-vs-suspend-interruptible on pipe A:C, connector eDP-1:HDMI-A-1
  2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
  2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
[cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:51 2017
[cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:08:12 2017
Stack trace:
  #0 [__igt_fail_assert+0x101]
  #1 [check_state+0x352]
  #2 [<unknown>+0x352]
Subtest 2x-vblank-vs-suspend-interruptible: FAIL (19.234s)
Stderr	
(kms_flip:2528) CRITICAL: Test assertion failure function check_state, file kms_flip.c:508:
(kms_flip:2528) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150
(kms_flip:2528) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:2528) CRITICAL: unexpected vblank seq 79902, should be < 79894
Subtest 2x-vblank-vs-suspend-interruptible failed.
**** DEBUG ****
(kms_flip:2528) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd)
(kms_flip:2528) DEBUG: Test requirement passed: modes
(kms_flip:2528) INFO: Beginning 2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=10240)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=10240)
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:11 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 2839.228791
current_received_ts = 2842.441589
current_seq = 79547
count = 0
seq_step = 10
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:30 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 2839.228791
last_received_ts = 2842.441589
last_seq = 79547
current_ts = 2842.593600
current_received_ts = 2846.109543
current_seq = 79627
count = 1
seq_step = 10
(kms_flip:2528) DEBUG: testing ts continuity: Current frame 79627, old frame 79547
(kms_flip:2528) INFO: 
2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1: PASSED

(kms_flip:2528) INFO: Beginning 2x-vblank-vs-suspend-interruptible on pipe A:C, connector eDP-1:HDMI-A-1
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=10240)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=10240)
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:51 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 2847.666128
current_received_ts = 2852.181685
current_seq = 79744
count = 0
seq_step = 10
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:08:12 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 2847.666128
last_received_ts = 2852.181685
last_seq = 79744
current_ts = 2852.344429
current_received_ts = 2856.890145
current_seq = 79902
count = 1
seq_step = 10
(kms_flip:2528) CRITICAL: Test assertion failure function check_state, file kms_flip.c:508:
(kms_flip:2528) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150
(kms_flip:2528) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:2528) CRITICAL: unexpected vblank seq 79902, should be < 79894
(kms_flip:2528) igt-core-INFO: Stack trace:
(kms_flip:2528) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:2528) igt-core-INFO:   #1 [check_state+0x352]
(kms_flip:2528) igt-core-INFO:   #2 [<unknown>+0x352]
****  END  ****