openbmc / openbmc

OpenBMC Distribution
Other
1.85k stars 904 forks source link

Withespoon Kernel CRTC: vblank wait timed out #3164

Closed cdeadmin closed 6 years ago

cdeadmin commented 6 years ago
SW426484
gkeishin commented 6 years ago
dmesg with debug enabled

root@witherspoon:~# dmesg
[   46.285314] ubi8: attached mtd8 (name "pnor", size 128 MiB)
[   46.290916] ubi8: PEB size: 65536 bytes (64 KiB), LEB size: 65408 bytes
[   46.297533] ubi8: min./max. I/O unit sizes: 1/256, sub-page size 1
[   46.303711] ubi8: VID header offset: 64 (aligned 64), data offset: 128
[   46.310237] ubi8: good PEBs: 2048, bad PEBs: 0, corrupted PEBs: 0
[   46.316330] ubi8: user volume: 6, internal volumes: 1, max. volumes count: 128
[   46.323546] ubi8: max/mean erase counter: 76/56, WL threshold: 4096, image sequence number: 1771973205
[   46.332840] ubi8: available PEBs: 520, total reserved PEBs: 1528, PEBs reserved for bad PEB handling: 0
[   46.342291] ubi8: background thread "ubi_bgt8d" started, PID 1133
[   50.380829] 8021q: adding VLAN 0 to HW filter on device eth0
[   50.410729] ftgmac100 1e660000.ethernet eth0: NCSI: Handler for packet type 0x82 returned -19
[   53.712793] ftgmac100 1e660000.ethernet eth0: NCSI: configuring channel 0
[   53.782165] ftgmac100 1e660000.ethernet eth0: NCSI: channel 0 config done
[   53.782202] ftgmac100 1e660000.ethernet eth0: NCSI: No more channels to process
[   53.782221] ftgmac100 1e660000.ethernet eth0: NCSI interface up
[   58.863925] block ubiblock8_0: created from ubi8:0(pnor-ro-9a2dfeba)
[   59.873844] UBIFS (ubi8:1): UBIFS: mounted UBI device 8, volume 1, name "pnor-rw-9a2dfeba"
[   59.882334] UBIFS (ubi8:1): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   59.891749] UBIFS (ubi8:1): FS size: 16155776 bytes (15 MiB, 247 LEBs), journal size 784896 bytes (0 MiB, 12 LEBs)
[   59.902095] UBIFS (ubi8:1): reserved for root: 763076 bytes (745 KiB)
[   59.908540] UBIFS (ubi8:1): media format: w5/r0 (latest is w5/r0), UUID 6AEDAF8D-929F-4CC3-A9F6-BC3DF18DA1B4, small LPT model
[   59.923287] UBIFS (ubi8:1): background thread "ubifs_bgt8_1" started, PID 1162
[   60.613435] UBIFS (ubi8:2): background thread "ubifs_bgt8_2" started, PID 1178
[   60.698905] UBIFS (ubi8:2): UBIFS: mounted UBI device 8, volume 2, name "pnor-prsv"
[   60.706592] UBIFS (ubi8:2): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   60.715985] UBIFS (ubi8:2): FS size: 1504384 bytes (1 MiB, 23 LEBs), journal size 523265 bytes (0 MiB, 6 LEBs)
[   60.726156] UBIFS (ubi8:2): reserved for root: 71055 bytes (69 KiB)
[   60.732443] UBIFS (ubi8:2): media format: w5/r0 (latest is w5/r0), UUID EC01E1C8-9696-4758-8BE0-6B74670C9409, small LPT model
[   73.933382] block ubiblock8_4: created from ubi8:4(pnor-ro-bfc0037c)
[   74.321911] UBIFS (ubi8:5): UBIFS: mounted UBI device 8, volume 5, name "pnor-rw-bfc0037c"
[   74.330402] UBIFS (ubi8:5): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   74.339957] UBIFS (ubi8:5): FS size: 16155776 bytes (15 MiB, 247 LEBs), journal size 784896 bytes (0 MiB, 12 LEBs)
[   74.350318] UBIFS (ubi8:5): reserved for root: 763076 bytes (745 KiB)
[   74.356769] UBIFS (ubi8:5): media format: w5/r0 (latest is w5/r0), UUID 385278F4-DD31-49DB-95BB-E20D3A780F81, small LPT model
[   74.373641] UBIFS (ubi8:5): background thread "ubifs_bgt8_5" started, PID 1217
[   75.604393] block ubiblock0_1: created from ubi0:1(rofs-b930f218)
[   97.119109] ftgmac100 1e660000.ethernet eth0: Set package 0x0, channel 0x0 as preferred
[   97.127484] ftgmac100 1e660000.ethernet eth0: NCSI: Stopping device
[   97.127508] ftgmac100 1e660000.ethernet eth0: NCSI interface down
[   97.133671] ftgmac100 1e660000.ethernet eth0: NCSI: configuring channel 0
[   97.144419] ftgmac100 1e660000.ethernet eth0: NCSI: channel 0 config done
[   97.144454] ftgmac100 1e660000.ethernet eth0: NCSI: No more channels to process
[   97.144510] ftgmac100 1e660000.ethernet eth0: NCSI interface up
[  107.253127] UBIFS (ubi8:3): background thread "ubifs_bgt8_3" started, PID 1337
[  107.276444] UBIFS (ubi8:3): UBIFS: mounted UBI device 8, volume 3, name "pnor-patch"
[  107.284222] UBIFS (ubi8:3): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[  107.293619] UBIFS (ubi8:3): FS size: 16155776 bytes (15 MiB, 247 LEBs), journal size 784896 bytes (0 MiB, 12 LEBs)
[  107.303961] UBIFS (ubi8:3): reserved for root: 763076 bytes (745 KiB)
[  107.310406] UBIFS (ubi8:3): media format: w5/r0 (latest is w5/r0), UUID C48E4DC3-D116-4CB0-B4B1-886F0D41A94D, small LPT model
[  154.573620] ftgmac100 1e660000.ethernet eth0: Set package 0x0, channel 0x0 as preferred
[  154.581663] ftgmac100 1e660000.ethernet eth0: NCSI: Stopping device
[  154.581681] ftgmac100 1e660000.ethernet eth0: NCSI interface down
[  154.587787] ftgmac100 1e660000.ethernet eth0: NCSI: configuring channel 0
[  154.598225] ftgmac100 1e660000.ethernet eth0: NCSI: channel 0 config done
[  154.598259] ftgmac100 1e660000.ethernet eth0: NCSI: No more channels to process
[  154.598276] ftgmac100 1e660000.ethernet eth0: NCSI interface up
[  190.068567] occ-hwmon occ-hwmon.1: failed to get OCC poll response: -19
[  190.606203] occ-hwmon occ-hwmon.2: failed to get OCC poll response: -19
[ 1012.011628] ucd9000 11-0064: Device ID UCD90160|2.3.4.0000|110603
[ 1017.636692] occ-hwmon occ-hwmon.1: failed to get OCC poll response: -19
[ 1018.481194] occ-hwmon occ-hwmon.2: failed to get OCC poll response: -19
[ 1024.495919] [drm:drm_atomic_state_init] Allocated atomic state 93aab940
[ 1024.495960] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 97da2c80 state to 93aab940
[ 1024.495989] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 91023600 state to 93aab940
[ 1024.496034] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 91023600
[ 1024.496058] [drm:drm_atomic_set_crtc_for_plane] Link plane state 97da2c80 to [CRTC:29:crtc-0]
[ 1024.496081] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 97da2c80
[ 1024.496108] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93aab940
[ 1024.496148] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 9107f360 state to 93aab940
[ 1024.496166] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9107f360 to [CRTC:29:crtc-0]
[ 1024.496182] [drm:drm_atomic_check_only] checking 93aab940
[ 1024.496235] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] mode changed
[ 1024.496257] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] enable changed
[ 1024.496274] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] active changed
[ 1024.496307] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[ 1024.496336] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] using [ENCODER:30:None-30] on [CRTC:29:crtc-0]
[ 1024.496357] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] needs all connectors, enable: y, active: y
[ 1024.496379] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93aab940
[ 1024.496432] [drm:drm_atomic_commit] committing 93aab940
[ 1024.496495] [drm:drm_atomic_helper_commit_modeset_disables] modeset on [ENCODER:30:None-30]
[ 1024.496552] [drm:drm_atomic_helper_commit_modeset_enables] enabling [CRTC:29:crtc-0]
[ 1024.496590] [drm:drm_atomic_helper_commit_modeset_enables] enabling [ENCODER:30:None-30]
[ 1024.513323] [drm:drm_atomic_state_default_clear] Clearing atomic state 93aab940
[ 1024.513367] [drm:__drm_atomic_state_free] Freeing atomic state 93aab940
[ 1024.513412] [drm:drm_atomic_state_init] Allocated atomic state 93aab940
[ 1024.513446] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 9e61ae80 state to 93aab940
[ 1024.513473] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 9e6c4400 state to 93aab940
[ 1024.513526] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 9e6c4400
[ 1024.513550] [drm:drm_atomic_set_crtc_for_plane] Link plane state 9e61ae80 to [CRTC:29:crtc-0]
[ 1024.513571] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 9e61ae80
[ 1024.513598] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93aab940
[ 1024.513631] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 9e66f000 state to 93aab940
[ 1024.513653] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9e66f000 to [NOCRTC]
[ 1024.513674] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9e66f000 to [CRTC:29:crtc-0]
[ 1024.513690] [drm:drm_atomic_check_only] checking 93aab940
[ 1024.513767] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[ 1024.513802] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[ 1024.513854] [drm:drm_atomic_commit] committing 93aab940
[ 1024.515875] ------------[ cut here ]------------
[ 1024.515950] WARNING: CPU: 0 PID: 2174 at /kernel-source//drivers/gpu/drm/drm_atomic_helper.c:1240 drm_atomic_helper_wait_for_vblanks.part.1+0x274/0x288
[ 1024.515988] [CRTC:29:crtc-0] vblank wait timed out
[ 1024.516011] CPU: 0 PID: 2174 Comm: fbset Not tainted 4.13.16-5b5a9c2d51eda4279915d72cda387dd6edfd00df #1
[ 1024.516017] Hardware name: Generic DT based system
[ 1024.516075] [<80109930>] (unwind_backtrace) from [<80106bc4>] (show_stack+0x20/0x24)
[ 1024.516121] [<80106bc4>] (show_stack) from [<805d3744>] (dump_stack+0x20/0x28)
[ 1024.516154] [<805d3744>] (dump_stack) from [<80116164>] (__warn+0xe0/0x108)
[ 1024.516242] [<80116164>] (__warn) from [<801161e0>] (warn_slowpath_fmt+0x54/0x74)
[ 1024.516287] [<801161e0>] (warn_slowpath_fmt) from [<8038dd60>] (drm_atomic_helper_wait_for_vblanks.part.1+0x274/0x288)
[ 1024.516326] [<8038dd60>] (drm_atomic_helper_wait_for_vblanks.part.1) from [<8038f914>] (drm_atomic_helper_commit_tail+0x64/0x74)
[ 1024.516353] [<8038f914>] (drm_atomic_helper_commit_tail) from [<8038f9b0>] (commit_tail+0x8c/0x90)
[ 1024.516377] [<8038f9b0>] (commit_tail) from [<8038ff08>] (drm_atomic_helper_commit+0x128/0x134)
[ 1024.516404] [<8038ff08>] (drm_atomic_helper_commit) from [<803b01cc>] (drm_atomic_commit+0x54/0x68)
[ 1024.516460] [<803b01cc>] (drm_atomic_commit) from [<80394038>] (restore_fbdev_mode_atomic+0x188/0x1f8)
[ 1024.516496] [<80394038>] (restore_fbdev_mode_atomic) from [<80395e8c>] (drm_fb_helper_pan_display+0xb0/0x1bc)
[ 1024.516551] [<80395e8c>] (drm_fb_helper_pan_display) from [<80351e44>] (fb_pan_display+0xb8/0x13c)
[ 1024.516617] [<80351e44>] (fb_pan_display) from [<80352038>] (fb_set_var+0x170/0x384)
[ 1024.516645] [<80352038>] (fb_set_var) from [<8035284c>] (do_fb_ioctl+0x534/0x7fc)
[ 1024.516668] [<8035284c>] (do_fb_ioctl) from [<80352b60>] (fb_ioctl+0x4c/0x5c)
[ 1024.516698] [<80352b60>] (fb_ioctl) from [<8022ae70>] (do_vfs_ioctl+0xac/0x92c)
[ 1024.516724] [<8022ae70>] (do_vfs_ioctl) from [<8022b734>] (SyS_ioctl+0x44/0x68)
[ 1024.516750] [<8022b734>] (SyS_ioctl) from [<80102640>] (ret_fast_syscall+0x0/0x3c)
[ 1024.516763] ---[ end trace 8ce8097cd88217f1 ]---
[ 1024.529976] [drm:drm_atomic_state_default_clear] Clearing atomic state 93aab940
[ 1024.530031] [drm:__drm_atomic_state_free] Freeing atomic state 93aab940
[ 1028.662885] [drm:drm_atomic_state_init] Allocated atomic state 97c19a00
[ 1028.662933] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 91028b00 state to 97c19a00
[ 1028.662967] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 97fe4c00 state to 97c19a00
[ 1028.663021] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 97fe4c00
[ 1028.663046] [drm:drm_atomic_set_crtc_for_plane] Link plane state 91028b00 to [CRTC:29:crtc-0]
[ 1028.663068] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 91028b00
[ 1028.663094] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 97c19a00
[ 1028.663130] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 9107f5a0 state to 97c19a00
[ 1028.663152] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9107f5a0 to [NOCRTC]
[ 1028.663173] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9107f5a0 to [CRTC:29:crtc-0]
[ 1028.663190] [drm:drm_atomic_check_only] checking 97c19a00
[ 1028.663264] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[ 1028.663303] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[ 1028.663355] [drm:drm_atomic_commit] committing 97c19a00
[ 1028.674680] [drm:drm_atomic_state_default_clear] Clearing atomic state 97c19a00
[ 1028.674735] [drm:__drm_atomic_state_free] Freeing atomic state 97c19a00
[ 1095.621053] ftgmac100 1e660000.ethernet eth0: NCSI: host driver not running on channel 0
[ 1120.040733] [drm:drm_atomic_state_init] Allocated atomic state 91128ec0
[ 1120.040779] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 93b4df80 state to 91128ec0
[ 1120.040810] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 972d8e00 state to 91128ec0
[ 1120.040866] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 972d8e00
[ 1120.040889] [drm:drm_atomic_set_crtc_for_plane] Link plane state 93b4df80 to [CRTC:29:crtc-0]
[ 1120.040913] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 93b4df80
[ 1120.040944] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 91128ec0
[ 1120.040980] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 93a1b6c0 state to 91128ec0
[ 1120.041002] [drm:drm_atomic_set_crtc_for_connector] Link connector state 93a1b6c0 to [NOCRTC]
[ 1120.041025] [drm:drm_atomic_set_crtc_for_connector] Link connector state 93a1b6c0 to [CRTC:29:crtc-0]
[ 1120.041042] [drm:drm_atomic_check_only] checking 91128ec0
[ 1120.041117] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[ 1120.041153] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[ 1120.041209] [drm:drm_atomic_commit] committing 91128ec0
[ 1120.068467] [drm:drm_atomic_state_default_clear] Clearing atomic state 91128ec0
[ 1120.068525] [drm:__drm_atomic_state_free] Freeing atomic state 91128ec0
[ 1122.015294] ftgmac100 1e660000.ethernet eth0: NCSI: LSC AEN - channel 0 state up
[ 1122.022956] ftgmac100 1e660000.ethernet eth0: NCSI: host driver running on channel 0
[ 1134.618566] ftgmac100 1e660000.ethernet eth0: NCSI: host driver not running on channel 0
[ 1163.618031] ftgmac100 1e660000.ethernet eth0: NCSI: host driver running on channel 0
root@witherspoon:~#
gkeishin commented 6 years ago

Another failure dmesg from different run

root@witherspoon:~# dmesg
[   21.054340] systemd[1]: Failed to insert module 'autofs4': No such file or directory
[   21.264758] systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=hybrid)
[   21.286949] systemd[1]: Detected architecture arm.
[   21.347952] systemd[1]: Set hostname to <witherspoon>.
[   23.233972] systemd[1]: Created slice System Slice.
[   23.269936] systemd[1]: Created slice system-phosphor\x2dreset\x2dsensor\x2dstates.slice.
[   23.309575] systemd[1]: Created slice system-org.openbmc.buttons.Power.slice.
[   23.350896] systemd[1]: Created slice system-phosphor\x2ddiscover\x2dsystem\x2dstate.slice.
[   23.409537] systemd[1]: Created slice system-phosphor\x2dreset\x2dhost\x2dcheck.slice.
[   23.447783] systemd[1]: Reached target Remote File Systems.
[   23.489566] systemd[1]: Created slice system-org.openbmc.control.Power.slice.
[   26.588798] systemd[1]: Started Load/Save Random Seed.
[   26.832066] systemd[1]: Started udev Kernel Device Manager.
[   26.858641] systemd[1]: Started Bind mount volatile /srv.
[   26.874563] systemd[1]: Reached target Local File Systems.
[   27.024441] systemd[1]: Starting Journal Service...
[   28.743659] systemd[1]: Started udev Coldplug all Devices.
[   28.866452] systemd[1]: Found device /dev/ttyS4.
[   29.318409] systemd[1]: Started Journal Service.
[   33.366825] systemd-journald[684]: Received request to flush runtime journal from PID 1
[   38.559181] ubi8: default fastmap pool size: 100
[   38.563829] ubi8: default fastmap WL pool size: 50
[   38.568621] ubi8: attaching mtd8
[   38.598260] ubi8: scanning is finished
[   38.742714] ubi8: attached mtd8 (name "pnor", size 128 MiB)
[   38.748317] ubi8: PEB size: 65536 bytes (64 KiB), LEB size: 65408 bytes
[   38.754938] ubi8: min./max. I/O unit sizes: 1/256, sub-page size 1
[   38.761114] ubi8: VID header offset: 64 (aligned 64), data offset: 128
[   38.767632] ubi8: good PEBs: 2048, bad PEBs: 0, corrupted PEBs: 0
[   38.773717] ubi8: user volume: 4, internal volumes: 1, max. volumes count: 128
[   38.780939] ubi8: max/mean erase counter: 77/57, WL threshold: 4096, image sequence number: 1771973205
[   38.790235] ubi8: available PEBs: 1136, total reserved PEBs: 912, PEBs reserved for bad PEB handling: 0
[   38.799680] ubi8: background thread "ubi_bgt8d" started, PID 1031
[   45.957165] block ubiblock8_0: created from ubi8:0(pnor-ro-3d8a0973)
[   46.584024] UBIFS (ubi8:1): UBIFS: mounted UBI device 8, volume 1, name "pnor-rw-3d8a0973"
[   46.592322] UBIFS (ubi8:1): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   46.601715] UBIFS (ubi8:1): FS size: 16155776 bytes (15 MiB, 247 LEBs), journal size 784896 bytes (0 MiB, 12 LEBs)
[   46.612050] UBIFS (ubi8:1): reserved for root: 763076 bytes (745 KiB)
[   46.618496] UBIFS (ubi8:1): media format: w5/r0 (latest is w5/r0), UUID 29464610-6132-4B59-82C8-7029D9F3D2CD, small LPT model
[   46.633029] UBIFS (ubi8:1): background thread "ubifs_bgt8_1" started, PID 1050
[   47.101281] UBIFS (ubi8:2): UBIFS: mounted UBI device 8, volume 2, name "pnor-prsv"
[   47.108968] UBIFS (ubi8:2): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   47.118526] UBIFS (ubi8:2): FS size: 1504384 bytes (1 MiB, 23 LEBs), journal size 523265 bytes (0 MiB, 6 LEBs)
[   47.128535] UBIFS (ubi8:2): reserved for root: 71055 bytes (69 KiB)
[   47.134805] UBIFS (ubi8:2): media format: w5/r0 (latest is w5/r0), UUID EC01E1C8-9696-4758-8BE0-6B74670C9409, small LPT model
[   47.152275] UBIFS (ubi8:2): background thread "ubifs_bgt8_2" started, PID 1058
[   48.469330] 8021q: adding VLAN 0 to HW filter on device eth0
[   48.486471] ftgmac100 1e660000.ethernet eth0: NCSI: Handler for packet type 0x82 returned -19
[   52.044162] ftgmac100 1e660000.ethernet eth0: NCSI: configuring channel 0
[   52.188511] ftgmac100 1e660000.ethernet eth0: NCSI: channel 0 config done
[   52.188546] ftgmac100 1e660000.ethernet eth0: NCSI: No more channels to process
[   52.188563] ftgmac100 1e660000.ethernet eth0: NCSI interface up
[   70.417388] block ubiblock0_1: created from ubi0:1(rofs-00efdb5a)
[   89.169436] ftgmac100 1e660000.ethernet eth0: Set package 0x0, channel 0x0 as preferred
[   89.177502] ftgmac100 1e660000.ethernet eth0: NCSI: Stopping device
[   89.177518] ftgmac100 1e660000.ethernet eth0: NCSI interface down
[   89.183647] ftgmac100 1e660000.ethernet eth0: NCSI: configuring channel 0
[   89.205240] ftgmac100 1e660000.ethernet eth0: NCSI: channel 0 config done
[   89.205273] ftgmac100 1e660000.ethernet eth0: NCSI: No more channels to process
[   89.205291] ftgmac100 1e660000.ethernet eth0: NCSI interface up
[   95.353396] UBIFS (ubi8:3): background thread "ubifs_bgt8_3" started, PID 1218
[   95.450013] UBIFS (ubi8:3): UBIFS: mounted UBI device 8, volume 3, name "pnor-patch"
[   95.457790] UBIFS (ubi8:3): LEB size: 65408 bytes (63 KiB), min./max. I/O unit sizes: 8 bytes/256 bytes
[   95.467183] UBIFS (ubi8:3): FS size: 16155776 bytes (15 MiB, 247 LEBs), journal size 784896 bytes (0 MiB, 12 LEBs)
[   95.477712] UBIFS (ubi8:3): reserved for root: 763076 bytes (745 KiB)
[   95.484178] UBIFS (ubi8:3): media format: w5/r0 (latest is w5/r0), UUID C48E4DC3-D116-4CB0-B4B1-886F0D41A94D, small LPT model
[  197.556238] ucd9000 11-0064: Device ID UCD90160|2.3.4.0000|110603
[  205.427513] occ-hwmon occ-hwmon.1: failed to get OCC poll response: -19
[  206.116734] occ-hwmon occ-hwmon.2: failed to get OCC poll response: -19
[  210.852959] [drm:drm_atomic_state_init] Allocated atomic state 93b7d740
[  210.853000] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 95fe0080 state to 93b7d740
[  210.853031] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 97f99800 state to 93b7d740
[  210.853077] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 97f99800
[  210.853099] [drm:drm_atomic_set_crtc_for_plane] Link plane state 95fe0080 to [CRTC:29:crtc-0]
[  210.853119] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 95fe0080
[  210.853144] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93b7d740
[  210.853182] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 93a4ec60 state to 93b7d740
[  210.853201] [drm:drm_atomic_set_crtc_for_connector] Link connector state 93a4ec60 to [CRTC:29:crtc-0]
[  210.853218] [drm:drm_atomic_check_only] checking 93b7d740
[  210.853270] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] mode changed
[  210.853292] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] enable changed
[  210.853308] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] active changed
[  210.853342] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[  210.853370] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] using [ENCODER:30:None-30] on [CRTC:29:crtc-0]
[  210.853391] [drm:drm_atomic_helper_check_modeset] [CRTC:29:crtc-0] needs all connectors, enable: y, active: y
[  210.853413] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93b7d740
[  210.853466] [drm:drm_atomic_commit] committing 93b7d740
[  210.853530] [drm:drm_atomic_helper_commit_modeset_disables] modeset on [ENCODER:30:None-30]
[  210.853586] [drm:drm_atomic_helper_commit_modeset_enables] enabling [CRTC:29:crtc-0]
[  210.853623] [drm:drm_atomic_helper_commit_modeset_enables] enabling [ENCODER:30:None-30]
[  211.002155] [drm:drm_atomic_state_default_clear] Clearing atomic state 93b7d740
[  211.002224] [drm:__drm_atomic_state_free] Freeing atomic state 93b7d740
[  211.002268] [drm:drm_atomic_state_init] Allocated atomic state 93b7d740
[  211.002325] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 9e6e2700 state to 93b7d740
[  211.002354] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 9e613600 state to 93b7d740
[  211.002405] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 9e613600
[  211.002428] [drm:drm_atomic_set_crtc_for_plane] Link plane state 9e6e2700 to [CRTC:29:crtc-0]
[  211.002449] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 9e6e2700
[  211.002474] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93b7d740
[  211.002532] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 9e5ff720 state to 93b7d740
[  211.002556] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9e5ff720 to [NOCRTC]
[  211.002576] [drm:drm_atomic_set_crtc_for_connector] Link connector state 9e5ff720 to [CRTC:29:crtc-0]
[  211.002613] [drm:drm_atomic_check_only] checking 93b7d740
[  211.002688] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[  211.002781] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[  211.002875] [drm:drm_atomic_commit] committing 93b7d740
[  211.010380] ------------[ cut here ]------------
[  211.010454] WARNING: CPU: 0 PID: 1689 at /kernel-source//drivers/gpu/drm/drm_atomic_helper.c:1240 drm_atomic_helper_wait_for_vblanks.part.1+0x274/0x288
[  211.010500] [CRTC:29:crtc-0] vblank wait timed out
[  211.010522] CPU: 0 PID: 1689 Comm: fbset Not tainted 4.13.16-5b5a9c2d51eda4279915d72cda387dd6edfd00df #1
[  211.010527] Hardware name: Generic DT based system
[  211.010584] [<80109930>] (unwind_backtrace) from [<80106bc4>] (show_stack+0x20/0x24)
[  211.010654] [<80106bc4>] (show_stack) from [<805d3744>] (dump_stack+0x20/0x28)
[  211.010688] [<805d3744>] (dump_stack) from [<80116164>] (__warn+0xe0/0x108)
[  211.010711] [<80116164>] (__warn) from [<801161e0>] (warn_slowpath_fmt+0x54/0x74)
[  211.010754] [<801161e0>] (warn_slowpath_fmt) from [<8038dd60>] (drm_atomic_helper_wait_for_vblanks.part.1+0x274/0x288)
[  211.010791] [<8038dd60>] (drm_atomic_helper_wait_for_vblanks.part.1) from [<8038f914>] (drm_atomic_helper_commit_tail+0x64/0x74)
[  211.010845] [<8038f914>] (drm_atomic_helper_commit_tail) from [<8038f9b0>] (commit_tail+0x8c/0x90)
[  211.010874] [<8038f9b0>] (commit_tail) from [<8038ff08>] (drm_atomic_helper_commit+0x128/0x134)
[  211.010904] [<8038ff08>] (drm_atomic_helper_commit) from [<803b01cc>] (drm_atomic_commit+0x54/0x68)
[  211.010952] [<803b01cc>] (drm_atomic_commit) from [<80394038>] (restore_fbdev_mode_atomic+0x188/0x1f8)
[  211.010986] [<80394038>] (restore_fbdev_mode_atomic) from [<80395e8c>] (drm_fb_helper_pan_display+0xb0/0x1bc)
[  211.011021] [<80395e8c>] (drm_fb_helper_pan_display) from [<80351e44>] (fb_pan_display+0xb8/0x13c)
[  211.011101] [<80351e44>] (fb_pan_display) from [<80352038>] (fb_set_var+0x170/0x384)
[  211.011132] [<80352038>] (fb_set_var) from [<8035284c>] (do_fb_ioctl+0x534/0x7fc)
[  211.011157] [<8035284c>] (do_fb_ioctl) from [<80352b60>] (fb_ioctl+0x4c/0x5c)
[  211.011189] [<80352b60>] (fb_ioctl) from [<8022ae70>] (do_vfs_ioctl+0xac/0x92c)
[  211.011212] [<8022ae70>] (do_vfs_ioctl) from [<8022b734>] (SyS_ioctl+0x44/0x68)
[  211.011238] [<8022b734>] (SyS_ioctl) from [<80102640>] (ret_fast_syscall+0x0/0x3c)
[  211.011252] ---[ end trace 0964172d69c51c06 ]---
[  211.019627] [drm:drm_atomic_state_default_clear] Clearing atomic state 93b7d740
[  211.019680] [drm:__drm_atomic_state_free] Freeing atomic state 93b7d740
[  212.837726] [drm:drm_atomic_state_init] Allocated atomic state 93b7da80
[  212.837770] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 93b7f200 state to 93b7da80
[  212.837800] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 93a45000 state to 93b7da80
[  212.837854] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 93a45000
[  212.837878] [drm:drm_atomic_set_crtc_for_plane] Link plane state 93b7f200 to [CRTC:29:crtc-0]
[  212.837902] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 93b7f200
[  212.837930] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93b7da80
[  212.837965] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 95f6bde0 state to 93b7da80
[  212.837988] [drm:drm_atomic_set_crtc_for_connector] Link connector state 95f6bde0 to [NOCRTC]
[  212.838008] [drm:drm_atomic_set_crtc_for_connector] Link connector state 95f6bde0 to [CRTC:29:crtc-0]
[  212.838024] [drm:drm_atomic_check_only] checking 93b7da80
[  212.838092] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[  212.838128] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[  212.838179] [drm:drm_atomic_commit] committing 93b7da80
[  212.843258] [drm:drm_atomic_state_default_clear] Clearing atomic state 93b7da80
[  212.843312] [drm:__drm_atomic_state_free] Freeing atomic state 93b7da80
[  282.253804] ftgmac100 1e660000.ethernet eth0: NCSI: host driver not running on channel 0
[  303.426412] [drm:drm_atomic_state_init] Allocated atomic state 93ad4380
[  303.426460] [drm:drm_atomic_get_plane_state] Added [PLANE:28:plane-0] 93aca200 state to 93ad4380
[  303.426490] [drm:drm_atomic_get_crtc_state] Added [CRTC:29:crtc-0] 93abd200 state to 93ad4380
[  303.426547] [drm:drm_atomic_set_mode_for_crtc] Set [MODE:800x600] for CRTC state 93abd200
[  303.426571] [drm:drm_atomic_set_crtc_for_plane] Link plane state 93aca200 to [CRTC:29:crtc-0]
[  303.426593] [drm:drm_atomic_set_fb_for_plane] Set [FB:35] for plane state 93aca200
[  303.426623] [drm:drm_atomic_add_affected_connectors] Adding all current connectors for [CRTC:29:crtc-0] to 93ad4380
[  303.426662] [drm:drm_atomic_get_connector_state] Added [CONNECTOR:27:Unknown-1] 97104c00 state to 93ad4380
[  303.426684] [drm:drm_atomic_set_crtc_for_connector] Link connector state 97104c00 to [NOCRTC]
[  303.426707] [drm:drm_atomic_set_crtc_for_connector] Link connector state 97104c00 to [CRTC:29:crtc-0]
[  303.426723] [drm:drm_atomic_check_only] checking 93ad4380
[  303.426801] [drm:drm_atomic_helper_check_modeset] Updating routing for [CONNECTOR:27:Unknown-1]
[  303.426841] [drm:drm_atomic_helper_check_modeset] [CONNECTOR:27:Unknown-1] keeps [ENCODER:30:None-30], now on [CRTC:29:crtc-0]
[  303.426893] [drm:drm_atomic_commit] committing 93ad4380
[  303.434920] [drm:drm_atomic_state_default_clear] Clearing atomic state 93ad4380
[  303.434979] [drm:__drm_atomic_state_free] Freeing atomic state 93ad4380
[  305.383433] ftgmac100 1e660000.ethernet eth0: NCSI: LSC AEN - channel 0 state up
[  305.451550] ftgmac100 1e660000.ethernet eth0: NCSI: host driver running on channel 0
[  318.051678] ftgmac100 1e660000.ethernet eth0: NCSI: host driver not running on channel 0
[  347.853883] ftgmac100 1e660000.ethernet eth0: NCSI: host driver running on channel 0
root@witherspoon:~#
rfrandse commented 6 years ago

https://gerrit.openbmc-project.xyz/11319 kernel: FSI, ASPEED SPI NOR, ASPEED GFX Resolves: #3164 Withespoon Kernel CRTC: vblank wait timed out