Hello everybody,

I yeasterday discovered pretty nasty issue in radeon / drm code, and page_flip locking code on my ThinkPad T43.

This is Intel 32 bit machine.

With r300 class GPU.

# lspci | grep VGA 01:00.0 VGA compatible controller: ATI Technologies Inc M22 [Mobility Radeon X300] # lspci -v | grep -A 10 VGA 01:00.0 VGA compatible controller: ATI Technologies Inc M22 [Mobility Radeon X300] (prog-if 00 [VGA controller]) Subsystem: IBM Device 056e Flags: bus master, fast devsel, latency 0, IRQ 16 Memory at c0000000 (32-bit, prefetchable) [size=128M] I/O ports at 2000 [size=256] Memory at b0100000 (32-bit, non-prefetchable) [size=64K] [virtual] Expansion ROM at b0120000 [disabled] [size=128K] Capabilities: [50] Power Management version 2 Capabilities: [58] Express Endpoint, MSI 00 Capabilities: [80] MSI: Enable- Count=1/1 Maskable- 64bit+ Kernel driver in use: radeon # lspci -n 01:00.0 0300: 1002:5460

(however kernel do not had MSI support compiled in)

# uname -a Linux sredniczarny 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 SMP Sat Sep 10 21:12:02 CEST 2011 i686 GNU/Linux

# gcc -v gcc version 4.6.1 (Debian 4.6.1-10)

compiled with -march=pentium-m

# cat /proc/cmdline BOOT_IMAGE=/vmlinuz-3.1.0-rc5-t43-prod-00079-ged2888e-dirty \ root=/dev/mapper/sredniczarny-root ro \ resume=/dev/mapper/sredniczarny-swap_1 \ thinkpad_acpi.fan_control=1 \ threadirqs pcie_aspm=force pci=use_crs apic loapic

# ver_linux Linux sredniczarny 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 SMP Sat Sep 10 21:12:02 CEST 2011 i686 GNU/Linux

Gnu C 4.6.1 Gnu make 3.81 binutils 2.21.53.20110910 1.11 util-linux ./scripts/ver_linux: 23: ./scripts/ver_linux: fdformat: not found mount support module-init-tools found Linux C Library 2.13 Dynamic linker (ldd) 2.13 Procps 3.2.8 Console-tools 0.2.3 oprofile 0.9.6 Sh-utils 8.5 Modules Loaded usb_storage nls_utf8 nls_cp437 vfat fat pci_stub vboxpci vboxnetadp vboxnetflt vboxdrv pktcdvd ebtable_nat ebtables acpi_cpufreq mperf xt_CHECKSUM lib80211_crypt_ccmp uinput nfsd hdaps radeonfb fb_ddc radeon sr_mod i2c_i801 cdrom ipw2200 ttm libipw drm_kms_helper drm i2c_algo_bit intel_agp cfbfillrect intel_gtt cfbcopyarea agpgart cfbimgblt raid456 async_memcpy async_pq async_xor xor async_raid6_recov async_tx raid6_pq pata_acpi #

I was using xfce4 for about an hour (with compositing), and I then got this

[ 2388.033247] BUG: sleeping function called from invalid context at kernel/mutex.c:271 [ 2388.033254] in_atomic(): 1, irqs_disabled(): 1, pid: 4883, name: Xorg [ 2388.033258] 2 locks held by Xorg/4883: [ 2388.033261] #0: (&dev->mode_config.mutex){+.+.+.}, at: [<f8be18d3>] drm_mode_page_flip_ioctl+0x43/0x1f0 [drm] [ 2388.033291] #1: (&(&dev->event_lock)->rlock#2){......}, at: [<f93d904d>] radeon_crtc_page_flip+0xcd/0x560 [radeon] [ 2388.033329] irq event stamp: 122016268 [ 2388.033331] hardirqs last enabled at (122016267): [<c1649ec1>] _raw_spin_unlock_irqrestore+0x31/0x60 [ 2388.033341] hardirqs last disabled at (122016268): [<c164a37d>] _raw_spin_lock_irqsave+0x1d/0x60 [ 2388.033347] softirqs last enabled at (122016074): [<c104fbbc>] irq_exit+0xac/0xb0 [ 2388.033355] softirqs last disabled at (122016073): [<c10245f6>] smp_apic_timer_interrupt+0x56/0x90 [ 2388.033364] Pid: 4883, comm: Xorg Not tainted 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 [ 2388.033368] Call Trace: [ 2388.033374] [<c10245f6>] ? smp_apic_timer_interrupt+0x56/0x90 [ 2388.033380] [<c10381f6>] __might_sleep+0xe6/0x120 [ 2388.033384] [<c1647e4e>] mutex_lock_nested+0x1e/0x2f0 [ 2388.033389] [<c164a3b7>] ? _raw_spin_lock_irqsave+0x57/0x60 [ 2388.033409] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.033426] [<f8be1a4b>] ? drm_mode_page_flip_ioctl+0x1bb/0x1f0 [drm] [ 2388.033440] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 2388.033451] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 2388.033469] [<f8be1890>] ? drm_mode_gamma_get_ioctl+0xd0/0xd0 [drm] [ 2388.033479] [<c107886b>] ? trace_hardirqs_off+0xb/0x10 [ 2388.033485] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 2388.033489] [<c1649ec1>] ? _raw_spin_unlock_irqrestore+0x31/0x60 [ 2388.033501] [<f8bd2f60>] ? drm_copy_field+0x70/0x70 [drm] [ 2388.033507] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 2388.033518] [<f8bd3869>] ? drm_read+0x69/0x170 [drm] [ 2388.033523] [<c10f4502>] ? fget_light+0x2e2/0x340 [ 2388.033528] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 2388.033533] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.033565] [ 2388.033566] ====================================================== [ 2388.033569] [ INFO: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected ] [ 2388.033573] 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 [ 2388.033575] ------------------------------------------------------ [ 2388.033579] Xorg/4883 [HC0[0]:SC0[0]:HE0:SE1] is trying to acquire: [ 2388.033581] (&dev->struct_mutex){+.+.+.}, at: [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.033602] [ 2388.033603] and this task is already holding: [ 2388.033606] (&(&dev->event_lock)->rlock#2){......}, at: [<f93d904d>] radeon_crtc_page_flip+0xcd/0x560 [radeon] [ 2388.033627] which would create a new lock dependency: [ 2388.033630] (&(&dev->event_lock)->rlock#2){......} -> (&dev->struct_mutex){+.+.+.} [ 2388.033639] [ 2388.033639] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 2388.033642] (&(&dev->vblank_time_lock)->rlock){..-...} [ 2388.033646] ... which became SOFTIRQ-irq-safe at: [ 2388.033649] [<c107b93b>] __lock_acquire+0x57b/0x1790 [ 2388.033654] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.033659] [<c164a3b0>] _raw_spin_lock_irqsave+0x50/0x60 [ 2388.033664] [<f8bd57f6>] vblank_disable_and_save+0x26/0x1a0 [drm] [ 2388.033674] [<f8bd5a25>] vblank_disable_fn+0xb5/0xd0 [drm] [ 2388.033684] [<c105602f>] run_timer_softirq+0x10f/0x240 [ 2388.033690] [<c104f840>] __do_softirq+0x90/0x140 [ 2388.033695] [<c104f9e7>] run_ksoftirqd+0xf7/0x1b0 [ 2388.033700] [<c10659b8>] kthread+0x68/0x70 [ 2388.033706] [<c164b23a>] kernel_thread_helper+0x6/0x10 [ 2388.033711] [ 2388.033712] to a SOFTIRQ-irq-unsafe lock: [ 2388.033714] (&dev->struct_mutex){+.+.+.} [ 2388.033718] ... which became SOFTIRQ-irq-unsafe at: [ 2388.033720] ... [<c107b98b>] __lock_acquire+0x5cb/0x1790 [ 2388.033726] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.033731] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.033736] [<f8bd88d0>] drm_get_minor+0x90/0x2c0 [drm] [ 2388.033746] [<f8bdacba>] drm_get_pci_dev+0xea/0x260 [drm] [ 2388.033757] [<f944af77>] radeon_pci_probe+0xbf/0xc6 [radeon] [ 2388.033776] [<c12767ad>] pci_device_probe+0x8d/0x120 [ 2388.033783] [<c1317446>] driver_probe_device+0x66/0x180 [ 2388.033790] [<c13175f1>] __driver_attach+0x91/0xa0 [ 2388.033794] [<c1316661>] bus_for_each_dev+0x41/0x70 [ 2388.033799] [<c131716c>] driver_attach+0x1c/0x20 [ 2388.033803] [<c1316e57>] bus_add_driver+0x1a7/0x280 [ 2388.033808] [<c1317ac5>] driver_register+0x65/0x120 [ 2388.033813] [<c1276522>] __pci_register_driver+0x52/0xd0 [ 2388.033817] [<f8bdaf3f>] drm_pci_init+0x10f/0x120 [drm] [ 2388.033828] [<f94810d4>] 0xf94810d4 [ 2388.033837] [<c100302f>] do_one_initcall+0x2f/0x180 [ 2388.033842] [<c1086606>] sys_init_module+0xb06/0x1c50 [ 2388.033848] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.033853] [ 2388.033854] other info that might help us debug this: [ 2388.033855] [ 2388.033858] Chain exists of: [ 2388.033858] &(&dev->vblank_time_lock)->rlock --> &(&dev->event_lock)->rlock --> &dev->struct_mutex [ 2388.033867] [ 2388.033869] Possible interrupt unsafe locking scenario: [ 2388.033870] [ 2388.033872] CPU0 CPU1 [ 2388.033874] ---- ---- [ 2388.033876] lock(&dev->struct_mutex); [ 2388.033880] local_irq_disable(); [ 2388.033883] lock(&(&dev->vblank_time_lock)->rlock); [ 2388.033887] lock(&(&dev->event_lock)->rlock); [ 2388.033892] <Interrupt> [ 2388.033893] lock(&(&dev->vblank_time_lock)->rlock); [ 2388.033898] [ 2388.033898] *** DEADLOCK *** [ 2388.033899] [ 2388.033902] 2 locks held by Xorg/4883: [ 2388.033904] #0: (&dev->mode_config.mutex){+.+.+.}, at: [<f8be18d3>] drm_mode_page_flip_ioctl+0x43/0x1f0 [drm] [ 2388.033919] #1: (&(&dev->event_lock)->rlock#2){......}, at: [<f93d904d>] radeon_crtc_page_flip+0xcd/0x560 [radeon] [ 2388.033942] [ 2388.033943] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 2388.033960] -> (&(&dev->vblank_time_lock)->rlock){..-...} ops: 261 { [ 2388.033967] IN-SOFTIRQ-W at: [ 2388.033970] [<c107b93b>] __lock_acquire+0x57b/0x1790 [ 2388.033976] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.033982] [<c164a3b0>] _raw_spin_lock_irqsave+0x50/0x60 [ 2388.033987] [<f8bd57f6>] vblank_disable_and_save+0x26/0x1a0 [drm] [ 2388.033999] [<f8bd5a25>] vblank_disable_fn+0xb5/0xd0 [drm] [ 2388.034009] [<c105602f>] run_timer_softirq+0x10f/0x240 [ 2388.034015] [<c104f840>] __do_softirq+0x90/0x140 [ 2388.034021] [<c104f9e7>] run_ksoftirqd+0xf7/0x1b0 [ 2388.034026] [<c10659b8>] kthread+0x68/0x70 [ 2388.034032] [<c164b23a>] kernel_thread_helper+0x6/0x10 [ 2388.034037] INITIAL USE at: [ 2388.034041] [<c107b662>] __lock_acquire+0x2a2/0x1790 [ 2388.034047] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034052] [<c164a3b0>] _raw_spin_lock_irqsave+0x50/0x60 [ 2388.034058] [<f8bd5ad0>] drm_vblank_get+0x90/0x260 [drm] [ 2388.034068] [<f8bd69ad>] drm_vblank_pre_modeset+0x4d/0x70 [drm] [ 2388.034080] [<f93cc431>] radeon_crtc_dpms+0x101/0x460 [radeon] [ 2388.034097] [<f8c45626>] drm_helper_disable_unused_functions+0x136/0x150 [drm_kms_helper] [ 2388.034106] [<f8c4514e>] drm_fb_helper_initial_config+0x1e/0x220 [drm_kms_helper] [ 2388.034113] [<f93e2730>] radeon_fbdev_init+0x90/0x100 [radeon] [ 2388.034133] [<f93db60f>] radeon_modeset_init+0x3af/0x930 [radeon] [ 2388.034152] [<f93b565e>] radeon_driver_load_kms+0xce/0x120 [radeon] [ 2388.034168] [<f8bdad1b>] drm_get_pci_dev+0x14b/0x260 [drm] [ 2388.034180] [<f944af77>] radeon_pci_probe+0xbf/0xc6 [radeon] [ 2388.034199] [<c12767ad>] pci_device_probe+0x8d/0x120 [ 2388.034205] [<c1317446>] driver_probe_device+0x66/0x180 [ 2388.034211] [<c13175f1>] __driver_attach+0x91/0xa0 [ 2388.034216] [<c1316661>] bus_for_each_dev+0x41/0x70 [ 2388.034222] [<c131716c>] driver_attach+0x1c/0x20 [ 2388.034227] [<c1316e57>] bus_add_driver+0x1a7/0x280 [ 2388.034232] [<c1317ac5>] driver_register+0x65/0x120 [ 2388.034238] [<c1276522>] __pci_register_driver+0x52/0xd0 [ 2388.034243] [<f8bdaf3f>] drm_pci_init+0x10f/0x120 [drm] [ 2388.034255] [<f94810d4>] 0xf94810d4 [ 2388.034260] [<c100302f>] do_one_initcall+0x2f/0x180 [ 2388.034265] [<c1086606>] sys_init_module+0xb06/0x1c50 [ 2388.034271] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.034276] } [ 2388.034278] ... key at: [<f8bef284>] __key.32947+0x0/0xffff6b4a [drm] [ 2388.034289] ... acquired at: [ 2388.034291] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034296] [<c164a3b0>] _raw_spin_lock_irqsave+0x50/0x60 [ 2388.034301] [<f8bd732a>] drm_handle_vblank_events+0x2a/0x150 [drm] [ 2388.034312] [<f8bd75a4>] drm_handle_vblank+0x154/0x1d0 [drm] [ 2388.034322] [<f93ea67a>] r100_irq_process+0x3fa/0x5e0 [radeon] [ 2388.034341] [<f93e4282>] radeon_driver_irq_handler_kms+0x12/0x20 [radeon] [ 2388.034360] [<c109d431>] irq_forced_thread_fn+0x21/0x50 [ 2388.034365] [<c109d34b>] irq_thread+0x11b/0x1a0 [ 2388.034369] [<c10659b8>] kthread+0x68/0x70 [ 2388.034374] [<c164b23a>] kernel_thread_helper+0x6/0x10 [ 2388.034379] [ 2388.034381] -> (&(&dev->event_lock)->rlock#2){......} ops: 267 { [ 2388.034388] INITIAL USE at: [ 2388.034391] [<c107b662>] __lock_acquire+0x2a2/0x1790 [ 2388.034397] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034403] [<c164a3b0>] _raw_spin_lock_irqsave+0x50/0x60 [ 2388.034408] [<f8bd732a>] drm_handle_vblank_events+0x2a/0x150 [drm] [ 2388.034420] [<f8bd75a4>] drm_handle_vblank+0x154/0x1d0 [drm] [ 2388.034430] [<f93ea67a>] r100_irq_process+0x3fa/0x5e0 [radeon] [ 2388.034450] [<f93e4282>] radeon_driver_irq_handler_kms+0x12/0x20 [radeon] [ 2388.034469] [<c109d431>] irq_forced_thread_fn+0x21/0x50 [ 2388.034474] [<c109d34b>] irq_thread+0x11b/0x1a0 [ 2388.034479] [<c10659b8>] kthread+0x68/0x70 [ 2388.034484] [<c164b23a>] kernel_thread_helper+0x6/0x10 [ 2388.034490] } [ 2388.034492] ... key at: [<f8bef30c>] __key.32935+0x0/0xffff6ac2 [drm] [ 2388.034503] ... acquired at: [ 2388.034505] [<c1079c24>] check_irq_usage+0x54/0xd0 [ 2388.034510] [<c107c0f7>] __lock_acquire+0xd37/0x1790 [ 2388.034515] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034519] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.034524] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.034542] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 2388.034553] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 2388.034563] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 2388.034567] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 2388.034571] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.034576] [ 2388.034578] [ 2388.034579] the dependencies between the lock to be acquired and SOFTIRQ-irq-unsafe lock: [ 2388.034595] -> (&dev->struct_mutex){+.+.+.} ops: 2595394 { [ 2388.034602] HARDIRQ-ON-W at: [ 2388.034605] [<c107b95f>] __lock_acquire+0x59f/0x1790 [ 2388.034611] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034616] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.034621] [<f8bd88d0>] drm_get_minor+0x90/0x2c0 [drm] [ 2388.034632] [<f8bdacba>] drm_get_pci_dev+0xea/0x260 [drm] [ 2388.034643] [<f944af77>] radeon_pci_probe+0xbf/0xc6 [radeon] [ 2388.034663] [<c12767ad>] pci_device_probe+0x8d/0x120 [ 2388.034669] [<c1317446>] driver_probe_device+0x66/0x180 [ 2388.034674] [<c13175f1>] __driver_attach+0x91/0xa0 [ 2388.034680] [<c1316661>] bus_for_each_dev+0x41/0x70 [ 2388.034685] [<c131716c>] driver_attach+0x1c/0x20 [ 2388.034690] [<c1316e57>] bus_add_driver+0x1a7/0x280 [ 2388.034696] [<c1317ac5>] driver_register+0x65/0x120 [ 2388.034701] [<c1276522>] __pci_register_driver+0x52/0xd0 [ 2388.034707] [<f8bdaf3f>] drm_pci_init+0x10f/0x120 [drm] [ 2388.034718] [<f94810d4>] 0xf94810d4 [ 2388.034723] [<c100302f>] do_one_initcall+0x2f/0x180 [ 2388.034728] [<c1086606>] sys_init_module+0xb06/0x1c50 [ 2388.034734] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.034740] SOFTIRQ-ON-W at: [ 2388.034743] [<c107b98b>] __lock_acquire+0x5cb/0x1790 [ 2388.034749] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034754] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.034759] [<f8bd88d0>] drm_get_minor+0x90/0x2c0 [drm] [ 2388.034770] [<f8bdacba>] drm_get_pci_dev+0xea/0x260 [drm] [ 2388.034781] [<f944af77>] radeon_pci_probe+0xbf/0xc6 [radeon] [ 2388.034801] [<c12767ad>] pci_device_probe+0x8d/0x120 [ 2388.034807] [<c1317446>] driver_probe_device+0x66/0x180 [ 2388.034812] [<c13175f1>] __driver_attach+0x91/0xa0 [ 2388.034818] [<c1316661>] bus_for_each_dev+0x41/0x70 [ 2388.034823] [<c131716c>] driver_attach+0x1c/0x20 [ 2388.034828] [<c1316e57>] bus_add_driver+0x1a7/0x280 [ 2388.034834] [<c1317ac5>] driver_register+0x65/0x120 [ 2388.034839] [<c1276522>] __pci_register_driver+0x52/0xd0 [ 2388.034845] [<f8bdaf3f>] drm_pci_init+0x10f/0x120 [drm] [ 2388.034856] [<f94810d4>] 0xf94810d4 [ 2388.034861] [<c100302f>] do_one_initcall+0x2f/0x180 [ 2388.034866] [<c1086606>] sys_init_module+0xb06/0x1c50 [ 2388.034872] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.034878] RECLAIM_FS-ON-W at: [ 2388.034881] [<c107a276>] mark_held_locks+0x66/0xf0 [ 2388.034887] [<c107aa4f>] lockdep_trace_alloc+0x7f/0xe0 [ 2388.034892] [<c10e79a8>] kmem_cache_alloc+0x28/0x1a0 [ 2388.034898] [<f8bd84da>] drm_master_create+0x1a/0x90 [drm] [ 2388.034910] [<f8bd3c5f>] drm_open+0x25f/0x530 [drm] [ 2388.034920] [<f8bd4669>] drm_stub_open+0xf9/0x1a0 [drm] [ 2388.034930] [<c10f6d82>] chrdev_open+0xd2/0x1d0 [ 2388.034936] [<c10f07a4>] __dentry_open.isra.15+0x104/0x2d0 [ 2388.034942] [<c10f1d4c>] nameidata_to_filp+0x5c/0x70 [ 2388.034948] [<c1100807>] do_last+0x407/0x7f0 [ 2388.034954] [<c1100ccd>] path_openat+0x9d/0x350 [ 2388.034960] [<c1100fcc>] do_filp_open+0x2c/0x80 [ 2388.034965] [<c10f1e48>] do_sys_open+0xe8/0x1b0 [ 2388.034970] [<c10f1f39>] sys_open+0x29/0x40 [ 2388.034976] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.034981] INITIAL USE at: [ 2388.034984] [<c107b662>] __lock_acquire+0x2a2/0x1790 [ 2388.034990] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.034996] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.035001] [<f8bd88d0>] drm_get_minor+0x90/0x2c0 [drm] [ 2388.035012] [<f8bdacba>] drm_get_pci_dev+0xea/0x260 [drm] [ 2388.035023] [<f944af77>] radeon_pci_probe+0xbf/0xc6 [radeon] [ 2388.035043] [<c12767ad>] pci_device_probe+0x8d/0x120 [ 2388.035048] [<c1317446>] driver_probe_device+0x66/0x180 [ 2388.035054] [<c13175f1>] __driver_attach+0x91/0xa0 [ 2388.035059] [<c1316661>] bus_for_each_dev+0x41/0x70 [ 2388.035065] [<c131716c>] driver_attach+0x1c/0x20 [ 2388.035070] [<c1316e57>] bus_add_driver+0x1a7/0x280 [ 2388.035075] [<c1317ac5>] driver_register+0x65/0x120 [ 2388.035081] [<c1276522>] __pci_register_driver+0x52/0xd0 [ 2388.035086] [<f8bdaf3f>] drm_pci_init+0x10f/0x120 [drm] [ 2388.035098] [<f94810d4>] 0xf94810d4 [ 2388.035103] [<c100302f>] do_one_initcall+0x2f/0x180 [ 2388.035108] [<c1086606>] sys_init_module+0xb06/0x1c50 [ 2388.035114] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.035119] } [ 2388.035121] ... key at: [<f8bef314>] __key.32936+0x0/0xffff6aba [drm] [ 2388.035132] ... acquired at: [ 2388.035134] [<c1079c24>] check_irq_usage+0x54/0xd0 [ 2388.035139] [<c107c0f7>] __lock_acquire+0xd37/0x1790 [ 2388.035143] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.035148] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.035153] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.035171] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 2388.035182] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 2388.035192] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 2388.035196] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 2388.035200] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 2388.035205] [ 2388.035207] [ 2388.035208] stack backtrace: [ 2388.035211] Pid: 4883, comm: Xorg Not tainted 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 [ 2388.035214] Call Trace: [ 2388.035220] [<c1640007>] ? printk+0x18/0x1a [ 2388.035225] [<c1079bc3>] check_usage+0x3d3/0x3e0 [ 2388.035231] [<c10853cb>] ? __module_text_address+0xb/0x60 [ 2388.035235] [<c107823c>] ? __bfs+0xfc/0x1f0 [ 2388.035240] [<c1079c24>] check_irq_usage+0x54/0xd0 [ 2388.035245] [<c107c0f7>] __lock_acquire+0xd37/0x1790 [ 2388.035250] [<c100d8db>] ? show_trace+0x1b/0x30 [ 2388.035255] [<c163edc4>] ? dump_stack+0x6e/0x75 [ 2388.035259] [<c107d067>] lock_acquire+0x87/0xb0 [ 2388.035277] [<f93d90a5>] ? radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.035282] [<c1647e8d>] mutex_lock_nested+0x5d/0x2f0 [ 2388.035300] [<f93d90a5>] ? radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.035305] [<c164a3b7>] ? _raw_spin_lock_irqsave+0x57/0x60 [ 2388.035324] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2388.035336] [<f8be1a4b>] ? drm_mode_page_flip_ioctl+0x1bb/0x1f0 [drm] [ 2388.035347] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 2388.035357] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 2388.035369] [<f8be1890>] ? drm_mode_gamma_get_ioctl+0xd0/0xd0 [drm] [ 2388.035375] [<c107886b>] ? trace_hardirqs_off+0xb/0x10 [ 2388.035379] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 2388.035384] [<c1649ec1>] ? _raw_spin_unlock_irqrestore+0x31/0x60 [ 2388.035394] [<f8bd2f60>] ? drm_copy_field+0x70/0x70 [drm] [ 2388.035398] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 2388.035407] [<f8bd3869>] ? drm_read+0x69/0x170 [drm] [ 2388.035412] [<c10f4502>] ? fget_light+0x2e2/0x340 [ 2388.035416] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 2388.035421] [<c164ac98>] sysenter_do_call+0x12/0x38

then about 400 second of nothing, and then again

[ 2718.766103] BUG: sleeping function called from invalid context at kernel/mutex.c:271 [ 2718.766111] in_atomic(): 1, irqs_disabled(): 1, pid: 4883, name: Xorg [ 2718.766114] INFO: lockdep is turned off. [ 2718.766117] irq event stamp: 122016268 [ 2718.766119] hardirqs last enabled at (122016267): [<c1649ec1>] _raw_spin_unlock_irqrestore+0x31/0x60 [ 2718.766132] hardirqs last disabled at (122016268): [<c164a37d>] _raw_spin_lock_irqsave+0x1d/0x60 [ 2718.766138] softirqs last enabled at (122016074): [<c104fbbc>] irq_exit+0xac/0xb0 [ 2718.766146] softirqs last disabled at (122016073): [<c10245f6>] smp_apic_timer_interrupt+0x56/0x90 [ 2718.766155] Pid: 4883, comm: Xorg Not tainted 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 [ 2718.766158] Call Trace: [ 2718.766164] [<c10245f6>] ? smp_apic_timer_interrupt+0x56/0x90 [ 2718.766170] [<c10381f6>] __might_sleep+0xe6/0x120 [ 2718.766175] [<c1647e4e>] mutex_lock_nested+0x1e/0x2f0 [ 2718.766180] [<c164a3b7>] ? _raw_spin_lock_irqsave+0x57/0x60 [ 2718.766213] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 2718.766220] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 2718.766240] [<f8be1a4b>] ? drm_mode_page_flip_ioctl+0x1bb/0x1f0 [drm] [ 2718.766252] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 2718.766262] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 2718.766273] [<f8be1890>] ? drm_mode_gamma_get_ioctl+0xd0/0xd0 [drm] [ 2718.766279] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 2718.766289] [<f8bd3846>] ? drm_read+0x46/0x170 [drm] [ 2718.766298] [<f8bd3869>] ? drm_read+0x69/0x170 [drm] [ 2718.766307] [<f8bd2f60>] ? drm_copy_field+0x70/0x70 [drm] [ 2718.766313] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 2718.766323] [<f8bd3869>] ? drm_read+0x69/0x170 [drm] [ 2718.766328] [<c10f4395>] ? fget_light+0x175/0x340 [ 2718.766334] [<c10fc965>] ? path_put+0x15/0x20 [ 2718.766338] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 2718.766343] [<c164ac98>] sysenter_do_call+0x12/0x38

and again 2 second later

and then about 100 times again every one or two seconds for about 7 minutes. ... [ 2720.955224] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 2721.972288] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 2723.069126] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 2724.195377] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ...

Rest of message exactly the same (including call trace and *irq enable/disable infos'

irq event stamp: 122016268

constantly for all this entries.

I somehow managed to switch to text console (read hit Ctrl-Alt-F1 and wait 5 minutes).

Then i suspendd system from root, and resumed, and after a while again

[ 5511.610325] BUG: sleeping function called from invalid context at kernel/mutex.c:271 [ 5511.610332] in_atomic(): 1, irqs_disabled(): 1, pid: 4883, name: Xorg [ 5511.610335] INFO: lockdep is turned off. [ 5511.610338] irq event stamp: 122016268 [ 5511.610341] hardirqs last enabled at (122016267): [<c1649ec1>] _raw_spin_unlock_irqrestore+0x31/0x60 [ 5511.610353] hardirqs last disabled at (122016268): [<c164a37d>] _raw_spin_lock_irqsave+0x1d/0x60 [ 5511.610359] softirqs last enabled at (122016074): [<c104fbbc>] irq_exit+0xac/0xb0 [ 5511.610367] softirqs last disabled at (122016073): [<c10245f6>] smp_apic_timer_interrupt+0x56/0x90 [ 5511.610376] Pid: 4883, comm: Xorg Not tainted 3.1.0-rc5-t43-prod-00079-ged2888e-dirty #11 [ 5511.610379] Call Trace: [ 5511.610385] [<c10245f6>] ? smp_apic_timer_interrupt+0x56/0x90 ...

started appearing every second. [ 5516.460928] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 5517.520232] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 5518.580410] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ... [ 5519.640420] BUG: sleeping function called from invalid context at kernel/mutex.c:271 ...

Everything including call stack and other info was still the same. including Pid and irq event stamp.

In totaly I had about 14301 call traces in kernel log.

For some reasone, few of the call traces have additionally prinkt at the bottom of the call trace, like this: ... [ 8836.910011] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 8836.910011] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 8836.910011] [<c1640000>] ? printk+0x11/0x1a

This additionall bottom have 1179 of all 14301 call traces.

Additionally there are few other vartiations:

In 40 cases I got this:

[ 9036.646048] Call Trace: [ 9036.646058] [<c10245f6>] ? smp_apic_timer_interrupt+0x56/0x90 [ 9036.646070] [<c10381f6>] __might_sleep+0xe6/0x120 [ 9036.646080] [<c1647e4e>] mutex_lock_nested+0x1e/0x2f0 [ 9036.646090] [<c164a3b7>] ? _raw_spin_lock_irqsave+0x57/0x60 [ 9036.646139] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 9036.646152] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 9036.646183] [<f8be1a4b>] ? drm_mode_page_flip_ioctl+0x1bb/0x1f0 [drm] [ 9036.646208] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 9036.646230] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 9036.646255] [<f8be1890>] ? drm_mode_gamma_get_ioctl+0xd0/0xd0 [drm] [ 9036.646267] [<c1037b8a>] ? check_preempt_curr+0x6a/0x80 [ 9036.646277] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 9036.646287] [<c106f8b5>] ? ktime_get+0x65/0x100 [ 9036.646296] [<c1023e96>] ? lapic_next_event+0x16/0x20 [ 9036.646307] [<c1075701>] ? clockevents_program_event+0x81/0x120 [ 9036.646317] [<c107698f>] ? tick_dev_program_event+0x3f/0x160 [ 9036.646338] [<f8bd2f60>] ? drm_copy_field+0x70/0x70 [drm] [ 9036.646349] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 9036.646359] [<c1076acc>] ? tick_program_event+0x1c/0x20 [ 9036.646370] [<c106a6d2>] ? hrtimer_interrupt+0x152/0x260 [ 9036.646381] [<c10f4395>] ? fget_light+0x175/0x340 [ 9036.646391] [<c10fc965>] ? path_put+0x15/0x20 [ 9036.646400] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 9036.646411] [<c164ac98>] sysenter_do_call+0x12/0x38

And 83 like this

[ 9344.357819] Call Trace: [ 9344.357824] [<c10245f6>] ? smp_apic_timer_interrupt+0x56/0x90 [ 9344.357830] [<c10381f6>] __might_sleep+0xe6/0x120 [ 9344.357835] [<c1647e4e>] mutex_lock_nested+0x1e/0x2f0 [ 9344.357840] [<c164a3b7>] ? _raw_spin_lock_irqsave+0x57/0x60 [ 9344.357870] [<f93d90a5>] radeon_crtc_page_flip+0x125/0x560 [radeon] [ 9344.357877] [<c125e79f>] ? do_raw_spin_lock+0x3f/0x130 [ 9344.357896] [<f8be1a4b>] ? drm_mode_page_flip_ioctl+0x1bb/0x1f0 [drm] [ 9344.357907] [<f8be19dd>] drm_mode_page_flip_ioctl+0x14d/0x1f0 [drm] [ 9344.357917] [<f8bd3143>] drm_ioctl+0x1e3/0x500 [drm] [ 9344.357929] [<f8be1890>] ? drm_mode_gamma_get_ioctl+0xd0/0xd0 [drm] [ 9344.357935] [<c1036f94>] ? finish_task_switch+0x74/0xd0 [ 9344.357941] [<c107a4cb>] ? trace_hardirqs_on+0xb/0x10 [ 9344.357946] [<c1649e72>] ? _raw_spin_unlock_irq+0x22/0x40 [ 9344.357951] [<c1036f94>] ? finish_task_switch+0x74/0xd0 [ 9344.357955] [<c1036f52>] ? finish_task_switch+0x32/0xd0 [ 9344.357965] [<f8bd2f60>] ? drm_copy_field+0x70/0x70 [drm] [ 9344.357970] [<c110380f>] do_vfs_ioctl+0x7f/0x5b0 [ 9344.357980] [<f8bd3869>] ? drm_read+0x69/0x170 [drm] [ 9344.357985] [<c107886b>] ? trace_hardirqs_off+0xb/0x10 [ 9344.357990] [<c10f4395>] ? fget_light+0x175/0x340 [ 9344.357996] [<c10fc965>] ? path_put+0x15/0x20 [ 9344.358000] [<c1103d6d>] sys_ioctl+0x2d/0x60 [ 9344.358005] [<c164ac98>] sysenter_do_call+0x12/0x38 [ 9344.358012] [<c1640000>] ? printk+0x11/0x1a

All other parameters and information in the BUG section was the same.

I do not know what will say lockdep about this other call traaces, as it automatically switches off after first discovered problem.

In all cases system started to become extreamly unresponsive, Xorg server was essentially doing nothing, keyboard was dead, and when I was trying to switch to console it took few minutes (unknown reason - there was no cpu load, no heavy io, no swap usage at the system at all). Killing screensaver didn't help in anyway.

I had also xview opened with VERY big image (pgm), and killed it too, after some time I had pretty responsive system. still in the same Xorg / xfce session I DISABLED compositing, but after a while problem started reapering again. Strange.

I inspected cpu and mem usage, but nothing strange really was happening.

When I was trying to go back to X console, sometimes it worked, but sometimes I got a strange black screen, which was actually flickering irregularry.

Now, I restarted whole Xorg (without rebooting system), and have xfce (without compositing), and no problem yet for few hours.

I do not know if this is regression ( i mostly use gnome). I also know that on my hardware, xfce with compositiing on 3.0.0 (distro kernel from debian) was extreamly slow (opening new window made xorg hang for 10 second). However it was working without any bugs. It however can be still the same problem, difference being just the fact that this hand-compiled in 3.1.0-rcX have enabled more debuging options including hang-task detection and lockdep, etc.

Xorg.log doesn't show anything interesting. Maybe this

... [ 79310.436] (++) using VT number 8

[ 79310.587] (II) Loading /usr/lib/xorg/modules/drivers/radeon_drv.so [ 79310.587] (II) [KMS] Kernel modesetting enabled. [ 79310.587] (**) RADEON(0): Depth 24, (--) framebuffer bpp 32 [ 79310.587] (II) RADEON(0): Pixel depth = 24 bits stored in 4 bytes (32 bpp pixmaps) [ 79310.587] (==) RADEON(0): Default visual is TrueColor [ 79310.587] (**) RADEON(0): Option "AccelDFS" "on" [ 79310.587] (**) RADEON(0): Option "ColorTiling" "on" [ 79310.587] (**) RADEON(0): Option "AccelMethod" "EXA" [ 79310.587] (**) RADEON(0): Option "DRI" "true" [ 79310.588] (==) RADEON(0): RGB weight 888 [ 79310.588] (II) RADEON(0): Using 8 bits per RGB (8 bit DAC) [ 79310.588] (--) RADEON(0): Chipset: "ATI Radeon Mobility X300 (M22) 5460 (PCIE)" (ChipID = 0x5460) [ 79310.588] (II) RADEON(0): PCIE card detected [ 79310.588] drmOpenDevice: node name is /dev/dri/card0 [ 79310.588] drmOpenDevice: open result is 9, (OK) [ 79310.588] drmOpenByBusid: Searching for BusID pci:0000:01:00.0 [ 79310.588] drmOpenDevice: node name is /dev/dri/card0 [ 79310.588] drmOpenDevice: open result is 9, (OK) [ 79310.588] drmOpenByBusid: drmOpenMinor returns 9 [ 79310.588] drmOpenByBusid: drmGetBusid reports pci:0000:01:00.0 [ 79310.588] (II) Loading sub module "exa" [ 79310.588] (II) LoadModule: "exa" [ 79310.589] (II) Loading /usr/lib/xorg/modules/libexa.so [ 79310.715] (II) Module exa: vendor="X.Org Foundation" [ 79310.715] compiled for 1.11.0, module version = 2.5.0 [ 79310.715] ABI class: X.Org Video Driver, version 11.0 [ 79310.715] (II) RADEON(0): KMS Color Tiling: enabled [ 79310.715] (II) RADEON(0): KMS Pageflipping: enabled [ 79310.715] (II) RADEON(0): SwapBuffers wait for vsync: enabled [ 79310.801] (II) RADEON(0): Output VGA-0 has no monitor section [ 79310.805] (II) RADEON(0): Output DVI-0 has no monitor section [ 79310.806] (II) RADEON(0): Output LVDS using monitor section Integrated LCD [ 79310.826] (II) RADEON(0): Output S-video has no monitor section [ 79310.835] (II) RADEON(0): EDID for output VGA-0 [ 79310.839] (II) RADEON(0): EDID for output DVI-0 [ 79310.840] (II) RADEON(0): EDID for output LVDS [ 79310.840] (II) RADEON(0): Printing probed modes for output LVDS [ 79310.840] (II) RADEON(0): Modeline "1400x1050"x50.0 84.96 1400 1432 1496 1600 1050 1051 1052 1062 (53.1 kHz) [ 79310.840] (II) RADEON(0): Modeline "1280x1024"x59.9 109.00 1280 1368 1496 1712 1024 1027 1034 1063 -hsync +vsync (63.7 kHz) [ 79310.840] (II) RADEON(0): Modeline "1280x960"x59.9 101.25 1280 1360 1488 1696 960 963 967 996 -hsync +vsync (59.7 kHz) [ 79310.840] (II) RADEON(0): Modeline "1280x854"x59.9 89.25 1280 1352 1480 1680 854 857 867 887 -hsync +vsync (53.1 kHz) [ 79310.840] (II) RADEON(0): Modeline "1280x800"x59.8 83.50 1280 1352 1480 1680 800 803 809 831 -hsync +vsync (49.7 kHz) [ 79310.840] (II) RADEON(0): Modeline "1280x720"x59.9 74.50 1280 1344 1472 1664 720 723 728 748 -hsync +vsync (44.8 kHz) [ 79310.840] (II) RADEON(0): Modeline "1152x768"x59.8 71.75 1152 1216 1328 1504 768 771 781 798 -hsync +vsync (47.7 kHz) [ 79310.840] (II) RADEON(0): Modeline "1024x768"x59.9 63.50 1024 1072 1176 1328 768 771 775 798 -hsync +vsync (47.8 kHz) [ 79310.840] (II) RADEON(0): Modeline "800x600"x59.9 38.25 800 832 912 1024 600 603 607 624 -hsync +vsync (37.4 kHz) [ 79310.840] (II) RADEON(0): Modeline "848x480"x59.7 31.50 848 872 952 1056 480 483 493 500 -hsync +vsync (29.8 kHz) [ 79310.840] (II) RADEON(0): Modeline "720x480"x59.7 26.75 720 744 808 896 480 483 493 500 -hsync +vsync (29.9 kHz) [ 79310.840] (II) RADEON(0): Modeline "640x480"x59.4 23.75 640 664 720 800 480 483 487 500 -hsync +vsync (29.7 kHz) [ 79310.864] (II) RADEON(0): EDID for output S-video [ 79310.864] (II) RADEON(0): Output VGA-0 disconnected [ 79310.864] (II) RADEON(0): Output DVI-0 disconnected [ 79310.864] (II) RADEON(0): Output LVDS connected [ 79310.864] (II) RADEON(0): Output S-video disconnected [ 79310.864] (II) RADEON(0): Using exact sizes for initial modes [ 79310.864] (II) RADEON(0): Output LVDS using initial mode 1400x1050 [ 79310.864] (II) RADEON(0): Using default gamma of (1.0, 1.0, 1.0) unless otherwise stated. [ 79310.864] (II) RADEON(0): mem size init: gart size :1fdff000 vram size: s:4000000 visible:3a1c000 [ 79310.864] (II) RADEON(0): EXA: Driver will allow EXA pixmaps in VRAM [ 79310.864] (==) RADEON(0): DPI set to (96, 96) [ 79310.864] (II) Loading sub module "fb" [ 79310.864] (II) LoadModule: "fb" [ 79310.864] (II) Loading /usr/lib/xorg/modules/libfb.so [ 79310.905] (II) Module fb: vendor="X.Org Foundation" [ 79310.905] compiled for 1.11.0, module version = 1.0.0 [ 79310.905] ABI class: X.Org ANSI C Emulation, version 0.4 [ 79310.905] (II) Loading sub module "ramdac" [ 79310.905] (II) LoadModule: "ramdac" [ 79310.905] (II) Module "ramdac" already built-in [ 79310.905] (--) Depth 24 pixmap format is 32 bpp [ 79310.906] (II) RADEON(0): [DRI2] Setup complete [ 79310.906] (II) RADEON(0): [DRI2] DRI driver: r300 [ 79310.906] (II) RADEON(0): Front buffer size: 18600K [ 79310.906] (II) RADEON(0): VRAM usage limit set to 36813K [ 79310.906] (==) RADEON(0): Backing store disabled [ 79310.906] (II) RADEON(0): Direct rendering enabled [ 79310.953] (II) RADEON(0): Render acceleration enabled for R300/R400/R500 type cards. [ 79310.953] (II) RADEON(0): Setting EXA maxPitchBytes [ 79310.953] (II) EXA(0): Driver allocated offscreen pixmaps [ 79310.953] (II) EXA(0): Driver registered support for the following operations: [ 79310.953] (II) Solid [ 79310.953] (II) Copy [ 79310.953] (II) Composite (RENDER acceleration) [ 79310.953] (II) UploadToScreen [ 79310.954] (II) DownloadFromScreen [ 79310.954] (II) RADEON(0): Acceleration enabled [ 79310.954] (==) RADEON(0): DPMS enabled [ 79310.954] (==) RADEON(0): Silken mouse enabled [ 79311.035] (II) RADEON(0): Set up textured video [ 79311.035] (II) RADEON(0): RandR 1.2 enabled, ignore the following RandR disabled message. ... [ 79311.126] (II) AIGLX: enabled GLX_MESA_copy_sub_buffer [ 79311.126] (II) AIGLX: enabled GLX_INTEL_swap_event [ 79311.126] (II) AIGLX: enabled GLX_SGI_swap_control and GLX_MESA_swap_control [ 79311.126] (II) AIGLX: enabled GLX_SGI_make_current_read [ 79311.126] (II) AIGLX: GLX_EXT_texture_from_pixmap backed by buffer objects [ 79311.128] (II) AIGLX: Loaded and initialized r300 [ 79311.128] (II) GLX: Initialized DRI2 GL provider for screen 0 [ 79311.183] (II) RADEON(0): Setting screen physical size to 370 x 277 [ 79311.183] (II) RADEON(0): Allocate new frame buffer 1400x1056 stride 1408 [ 79311.183] (II) RADEON(0): VRAM usage limit set to 48326K

Other relevant software ( I use Debian/GNU Linux, unstable)

ii libdrm-radeon1 2.4.26-1 Userspace interface to radeon-specific kernel DRM services -- runtime ii libdrm2 2.4.26-1 Userspace interface to kernel DRM services -- runtime ii xorg 1:7.6+8 X.Org X Window System ii xserver-xorg 1:7.6+8 X.Org X server ii xserver-xorg-core 2:1.11.0-1 Xorg X server - core server ii xserver-xorg-input-all 1:7.6+8 X.Org X server -- input driver metapackage ii xserver-xorg-input-evdev 1:2.6.0-2+b2 X.Org X server -- evdev input driver ii xserver-xorg-input-synaptics 1.4.1-1+b1 Synaptics TouchPad driver for X.Org server ii xserver-xorg-video-fbdev 1:0.4.2-4+b2 X.Org X server -- fbdev display driver ii xserver-xorg-video-radeon 1:6.14.2-2 X.Org X server -- AMD/ATI Radeon display driver ii libegl1-mesa 7.11-5 free implementation of the EGL API -- runtime ii libegl1-mesa-drivers 7.11-5 free implementation of the EGL API -- hardware drivers ii libgl1-mesa-dri 7.11-5 free implementation of the OpenGL API -- DRI modules ii libgl1-mesa-glx 7.11-5 free implementation of the OpenGL API -- GLX runtime ii libglapi-mesa 7.11-5 free implementation of the GL API -- shared library ii libglu1-mesa 7.11-5 Mesa OpenGL utility library (GLU) ii libopenvg1-mesa 7.11-5 free implementation of the OpenVG API -- runtime ii libosmesa6 7.11-5 Mesa Off-screen rendering extension ii mesa-utils 8.0.1-2+b1 Miscellaneous Mesa GL utilities ii xfwm4 4.8.1-4 window manager of the Xfce project ii xfce4 4.8.0.3 Meta-package for the Xfce Lightweight Desktop Environment ii xfdesktop4 4.8.2-2 xfce desktop background, icons and root menu manager

I was using clear xfce profile. It was create maybe 5 days ago.

Kernel config attached.

Regards, Witek