You are not logged in.

Pages: 1

#1 2014-06-19 14:02:34

hazardousagreement
Member
Registered: 2014-01-28
Posts: 3

[SOLVED] drm:radeon delays xorg start for 30 secs

Does anyone knows about a reason why starting the X.org server could take over 30 seconds?

Jun 19 15:15:59 Arch kernel: [drm] Initialized drm 1.1.0 20060810
Jun 19 15:15:59 Arch kernel: [drm] radeon kernel modesetting enabled.
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: enabling device (0006 -> 0007)
Jun 19 15:15:59 Arch kernel: [drm] initializing kernel modesetting (OLAND 0x1002:0x6600 0x17AA:0x3801).
Jun 19 15:15:59 Arch kernel: [drm] register mmio base: 0xB8000000
Jun 19 15:15:59 Arch kernel: [drm] register mmio size: 262144
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: VRAM: 2048M 0x0000000000000000 - 0x000000007FFFFFFF (2048M used)
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: GTT: 1024M 0x0000000080000000 - 0x00000000BFFFFFFF
Jun 19 15:15:59 Arch kernel: [drm] Detected VRAM RAM=2048M, BAR=128M
Jun 19 15:15:59 Arch kernel: [drm] RAM width 128bits DDR
Jun 19 15:15:59 Arch kernel: [drm] radeon: 2048M of VRAM memory ready
Jun 19 15:15:59 Arch kernel: [drm] radeon: 1024M of GTT memory ready.
Jun 19 15:15:59 Arch kernel: [drm] Loading OLAND Microcode
Jun 19 15:15:59 Arch kernel: [drm] radeon/OLAND_mc2.bin: 31452 bytes
Jun 19 15:15:59 Arch kernel: [drm] Internal thermal controller without fan control
Jun 19 15:15:59 Arch kernel: [drm] probing gen 2 caps for device 8086:c01 = 261ad03/e
Jun 19 15:15:59 Arch kernel: [drm] radeon: dpm initialized
Jun 19 15:15:59 Arch kernel: [drm] GART: num cpu pages 262144, num gpu pages 262144
Jun 19 15:15:59 Arch kernel: [drm] probing gen 2 caps for device 8086:c01 = 261ad03/e
Jun 19 15:15:59 Arch kernel: [drm] PCIE gen 3 link speeds already enabled
Jun 19 15:15:59 Arch kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000276000).
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: WB enabled
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000080000c00 and cpu addr 0xffff880157a34c00
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 1 use gpu addr 0x0000000080000c04 and cpu addr 0xffff880157a34c04
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 2 use gpu addr 0x0000000080000c08 and cpu addr 0xffff880157a34c08
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000080000c0c and cpu addr 0xffff880157a34c0c
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 4 use gpu addr 0x0000000080000c10 and cpu addr 0xffff880157a34c10
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x0000000000075a18 and cpu addr 0xffffc900111b5a18
Jun 19 15:15:59 Arch kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
Jun 19 15:15:59 Arch kernel: [drm] Driver supports precise vblank timestamp query.
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: irq 41 for MSI/MSI-X
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: radeon: using MSI.
Jun 19 15:15:59 Arch kernel: [drm] radeon: irq initialized.
Jun 19 15:15:59 Arch kernel: [drm] ring test on 0 succeeded in 1 usecs
Jun 19 15:15:59 Arch kernel: [drm] ring test on 1 succeeded in 1 usecs
Jun 19 15:15:59 Arch kernel: [drm] ring test on 2 succeeded in 1 usecs
Jun 19 15:15:59 Arch kernel: [drm] ring test on 3 succeeded in 2 usecs
Jun 19 15:15:59 Arch kernel: [drm] ring test on 4 succeeded in 1 usecs
Jun 19 15:15:59 Arch kernel: [drm] ring test on 5 succeeded in 2 usecs
Jun 19 15:15:59 Arch kernel: [drm] UVD initialized successfully.
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 0 succeeded in 0 usecs
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 1 succeeded in 0 usecs
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 2 succeeded in 0 usecs
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 3 succeeded in 0 usecs
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 4 succeeded in 1 usecs
Jun 19 15:15:59 Arch kernel: [drm] ib test on ring 5 succeeded
Jun 19 15:15:59 Arch kernel: [drm] Radeon Display Connectors
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: No connectors reported connected with modes
Jun 19 15:15:59 Arch kernel: [drm] Cannot find any crtc or sizes - going 1024x768
Jun 19 15:15:59 Arch kernel: [drm] fb mappable at 0xB047A000
Jun 19 15:15:59 Arch kernel: [drm] vram apper at 0xB0000000
Jun 19 15:15:59 Arch kernel: [drm] size 3145728
Jun 19 15:15:59 Arch kernel: [drm] fb depth is 24
Jun 19 15:15:59 Arch kernel: [drm]    pitch is 4096
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: fb1: radeondrmfb frame buffer device
Jun 19 15:15:59 Arch kernel: radeon 0000:01:00.0: registered panic notifier
Jun 19 15:15:59 Arch kernel: [drm] Initialized radeon 2.38.0 20080528 for 0000:01:00.0 on minor 0
Jun 19 15:15:59 Arch kernel: [drm] Memory usable by graphics device = 2048M
Jun 19 15:15:59 Arch kernel: fb: switching to inteldrmfb from VESA VGA
Jun 19 15:16:00 Arch kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
Jun 19 15:16:00 Arch kernel: [drm] Driver supports precise vblank timestamp query.
Jun 19 15:16:01 Arch kernel: fbcon: inteldrmfb (fb0) is primary device
Jun 19 15:16:01 Arch kernel: i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
Jun 19 15:16:01 Arch kernel: [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 1
Jun 19 15:16:02 Arch kernel: [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
[...]
Jun 19 15:16:01 Arch slim[818]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jun 19 15:16:01 2014
Jun 19 15:16:01 Arch slim[818]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Jun 19 15:16:01 Arch slim[818]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
[...]
Jun 19 15:16:30 Arch kernel: ACPI: \_SB_.PCI0.PEG0: ACPI_NOTIFY_BUS_CHECK event
Jun 19 15:16:30 Arch kernel: ACPI: \_SB_.PCI0.PEG0: Bus check in hotplug_event()
Jun 19 15:16:30 Arch kernel: radeon 0000:01:00.0: Refused to change power state, currently in D3
Jun 19 15:16:30 Arch kernel: radeon 0000:01:00.0: Refused to change power state, currently in D3
Jun 19 15:16:30 Arch kernel: radeon 0000:01:00.0: Refused to change power state, currently in D3
Jun 19 15:16:31 Arch systemd[1]: Started CUPS Printing Service.
Jun 19 15:16:35 Arch kernel: [drm:atom_op_jump] *ERROR* atombios stuck in loop for more than 5secs aborting
Jun 19 15:16:35 Arch kernel: [drm:atom_execute_table_locked] *ERROR* atombios stuck executing CB7E (len 62, WS 0, PS 0) @ 0xCB9A
Jun 19 15:16:35 Arch kernel: [drm:atom_execute_table_locked] *ERROR* atombios stuck executing B73E (len 236, WS 4, PS 0) @ 0xB80B
Jun 19 15:16:35 Arch kernel: [drm:atom_execute_table_locked] *ERROR* atombios stuck executing B698 (len 78, WS 12, PS 8) @ 0xB6A0
Jun 19 15:16:35 Arch kernel: [drm:si_dpm_enable] *ERROR* si_init_smc_table failed
Jun 19 15:16:35 Arch kernel: [drm:radeon_pm_resume_dpm] *ERROR* radeon: dpm resume failed
Jun 19 15:16:35 Arch kernel: [drm] probing gen 2 caps for device 8086:c01 = 2618d03/e
Jun 19 15:16:35 Arch kernel: [drm] enabling PCIE gen 3 link speeds, disable with radeon.pcie_gen2=0
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: Wait for MC idle timedout !
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: Wait for MC idle timedout !
Jun 19 15:16:36 Arch kernel: [drm] PCIE GART of 1024M enabled (table at 0x0000000000276000).
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: WB enabled
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000080000c00 and cpu addr 0xffff880157a34c00
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 1 use gpu addr 0x0000000080000c04 and cpu addr 0xffff880157a34c04
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 2 use gpu addr 0x0000000080000c08 and cpu addr 0xffff880157a34c08
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000080000c0c and cpu addr 0xffff880157a34c0c
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 4 use gpu addr 0x0000000080000c10 and cpu addr 0xffff880157a34c10
Jun 19 15:16:36 Arch kernel: radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x0000000000075a18 and cpu addr 0xffffc900111b5a18
Jun 19 15:16:38 Arch kernel: [drm:r600_ring_test] *ERROR* radeon: ring 0 test failed (scratch(0x850C)=0xFFFFFFFF)
Jun 19 15:16:38 Arch kernel: [drm:si_resume] *ERROR* si startup failed on resume
Jun 19 15:16:38 Arch kernel: [drm:si_dpm_enable] *ERROR* si_init_smc_table failed
Jun 19 15:16:38 Arch kernel: [drm:radeon_pm_resume_dpm] *ERROR* radeon: dpm resume failed
Jun 19 15:16:38 Arch slim[818]: Initializing built-in extension Generic Event Extension
Jun 19 15:16:38 Arch slim[818]: Initializing built-in extension SHAPE
[..]
Jun 19 15:16:38 Arch slim[818]: Initializing built-in extension XFree86-DGA
Jun 19 15:16:38 Arch slim[818]: Initializing built-in extension XFree86-DRI
Jun 19 15:16:38 Arch slim[818]: Initializing built-in extension DRI2
Jun 19 15:16:38 Arch kernel: radeon 0000:01:00.0: couldn't schedule ib
Jun 19 15:16:38 Arch slim[818]: Loading extension GLX
Jun 19 15:16:38 Arch acpid[524]: client connected from 833[0:0]
Jun 19 15:16:38 Arch acpid[524]: 1 client rule loaded
Jun 19 15:16:38 Arch kernel: radeon 0000:01:00.0: couldn't schedule ib
Jun 19 15:16:38 Arch kernel: radeon 0000:01:00.0: couldn't schedule ib

Last edited by hazardousagreement (2014-06-19 21:12:23)

Offline

#2 2014-06-19 19:20:57

slacknatcher
Member
From: Chile
Registered: 2009-07-08
Posts: 55

Re: [SOLVED] drm:radeon delays xorg start for 30 secs

Are you sure is drm:radeon the culprit?. those logs aren't as helpful one would think.

Jun 19 15:16:01 Arch slim[818]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jun 19 15:16:01 2014

Read that file and see what it tells.

Also use systemd-analyze and check if you use pulseaudio, this last one takes it time to start up.

Miscusi for my bad english

Offline

#3 2014-06-19 21:11:31

hazardousagreement
Member
Registered: 2014-01-28
Posts: 3

Re: [SOLVED] drm:radeon delays xorg start for 30 secs

Hi slacknatcher,

thanks for your help :-)

I looked at this logfile and there xorg just stops for thirty seconds.

[    35.404] (II) xfree86: Adding drm device (/dev/dri/card0)
[    55.110] (II) xfree86: Adding drm device (/dev/dri/card1)

So I figured out that for mysterious reasons the radeon kernel module was loaded twice.
I removed it from the kernel modules (mkinitcpi.conf) and the thirty second lag vanished smile

Offline

Pages: 1

Board footer

Powered by FluxBB