Bug 26087

Summary: Stalls with RV635 with KMS
Product: DRI Reporter: Shawn Starr <shawn.starr>
Component: DRM/RadeonAssignee: Default DRI bug account <dri-devel>
Status: RESOLVED INVALID QA Contact:
Severity: normal    
Priority: medium    
Version: XOrg git   
Hardware: Other   
OS: All   
Whiteboard:
i915 platform: i915 features:
Attachments:
Description Flags
DEBUG info for r6xx irq
none
r635 dmesg with patch debug output
none
Avoid irq contention ?
none
Kernel configuration I use
none
Lock stats over 10 minutes running game
none
.config none

Description Shawn Starr 2010-01-17 13:43:14 UTC 
Kernel output from when glxgears was started and ended:

Jan 17 16:39:35 segfault kernel: [   87.565574] r600_irq_process start: rptr 18144, wptr 18160                   
Jan 17 16:39:35 segfault kernel: [   87.565583] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:47 segfault kernel: [  100.016391] r600_irq_process start: rptr 19856, wptr 19872                   
Jan 17 16:39:48 segfault kernel: [  100.322119] pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  100.340597] r600_irq_process start: rptr 23424, wptr 23440                   
Jan 17 16:39:48 segfault kernel: [  100.357359] IH: CP int: 0x00000000                                           
Jan 17 16:39:48 segfault kernel: [  100.415541] r600_irq_set: vblank 0                                           
Jan 17 16:39:48 segfault kernel: [  100.415545] r600_irq_set: vblank 1                                           
Jan 17 16:39:48 segfault kernel: [  100.514587] r600_irq_process start: rptr 25184, wptr 25200                   
Jan 17 16:39:48 segfault kernel: [  100.514597] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  100.547930] IH: D1 vblank                                                    
Jan 17 16:39:48 segfault kernel: [  100.581261] pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  100.597928] r600_irq_process start: rptr 25600, wptr 25616                   
Jan 17 16:39:48 segfault kernel: [  100.647928] r600_irq_process start: rptr 26624, wptr 26640                   
Jan 17 16:39:48 segfault kernel: [  100.647943] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  100.697941] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  100.697988] r600_irq_process start: rptr 29696, wptr 29712                   
Jan 17 16:39:48 segfault kernel: [  100.712591] r600_irq_process start: rptr 30560, wptr 30576                   
Jan 17 16:39:48 segfault kernel: <c0086464, nr=0x64, dev 0xe200, auth=1                                          
Jan 17 16:39:48 segfault kernel: m:r_irq:drmcmd=uth= [d6464xe20rm_i cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1  
Jan 17 16:39:48 segfault kernel: <pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                          
Jan 17 16:39:48 segfault kernel: nr=0x64, dev 0xe200, auth=1                                                     
Jan 17 16:39:48 segfault kernel: t: vblank 1                                                                     
Jan 17 16:39:48 segfault kernel: <id=2464 100m_ioctl], pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1     
Jan 17 16:39:48 segfault kernel: [  101.046166] r600_irq_process start: rptr 45712, wptr 45728                   
Jan 17 16:39:48 segfault kernel: [  101.047953] r600_irq_process start: rptr 45840, wptr 45856                   
Jan 17 16:39:48 segfault kernel: [  101.047966] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:48 segfault kernel: [  101.053463] r600_irq_process start: rptr 46176, wptr 46192                   
Jan 17 16:39:49 segfault kernel: [  101.097966] pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:49 segfault kernel: [  101.114634] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:49 segfault kernel: <c0206466, nr=0x66, dev 0xe200, auth=1                                          
Jan 17 16:39:49 segfault kernel: <drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1              
Jan 17 16:39:49 segfault kernel: 9657] [drm:drmd=0x9661] [drm:drm_ioctl], ret = fffffff0                         
Jan 17 16:39:49 segfault kernel: <79675] [drmd=0x9679] [drm:drm_ioctl], ret = fffffff0                           
Jan 17 16:39:49 segfault kernel: <7020645e, nr=0x5e, dev 0xe200, auth=1                                          
Jan 17 16:39:49 segfault kernel: [  101.277467] pid=2793, cmd=0xc01c64a3, nr=0xa3, dev 0xe200, auth=1            
Jan 17 16:39:49 segfault kernel: 305594] [drm:r600_irq_set], r600_irq_set: vblank 0                              
Jan 17 16:39:49 segfault kernel: <] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                
Jan 17 16:39:49 segfault kernel: <7] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=] [drm:drm_ioctl], pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1                                                                                          
Jan 17 16:39:49 segfault kernel: < hpd 1                                                                         
Jan 17 16:39:49 segfault kernel: 101.329631] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                                                                               
Jan 17 16:39:49 segfault kernel: <101.350478] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                                                                              
Jan 17 16:39:49 segfault kernel: <7md=0x40086409, nr=0x09, dev 0xe200, auth=1                                    
Jan 17 16:39:49 segfault kernel: <7[drm466,e200, auth=1                                                          
Jan 17 16:39:49 segfault kernel: <7[drm:drm_ioctl], pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1        
Jan 17 16:39:49 segfault kernel: <7tl], pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1                    
Jan 17 16:39:49 segfault kernel: [  101.400705] r600_irq_process start: rptr 54208, wptr 54224                   
Jan 17 16:39:49 segfault kernel:  101.410993] [drm:r600_irq_set], r600_irq_set: hpd 1                            
Jan 17 16:39:49 segfault kernel: [  101.431322] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:49 segfault kernel: : vblank 0                                                                      
Jan 17 16:39:49 segfault kernel: 00_im:r600_irq_process], r600_irq_process start: rptr 56832, wptr 56848         
Jan 17 16:39:49 segfault kernel: pid=6464, nr=0x64, dev 0xe200, auth=1                                           
Jan 17 16:39:49 segfault kernel: <d=3277, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1                            
Jan 17 16:39:49 segfault kernel: pid=d, 01.5iocx5e, h=1                                                          
Jan 17 16:39:49 segfault kernel: [  101.581326] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:49 segfault kernel: [  101.581332] IH: D1 vblank                                                    
Jan 17 16:39:49 segfault kernel: [  101.597994] r600_irq_process start: rptr 60416, wptr 60432                   
Jan 17 16:39:49 segfault kernel: _ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                  
Jan 17 16:39:49 segfault kernel: <7] [dev 0xe200, auth=1                                                         
Jan 17 16:39:49 segfault kernel: [dr646a00, ioct<7>=279a, n01.8ioctl], ret = fffffff0                            
Jan 17 16:39:49 segfault kernel: 0_irq_set], r600_irq_set: hpd 3                                                 
Jan 17 16:39:49 segfault kernel: ] [drm:r600_irq_set], r600_irq_set: vblank 1                                    
Jan 17 16:39:49 segfault kernel: <72] [drm:drm_ioctl], ret = fffffff0                                            
Jan 17 16:39:49 segfault kernel: <7600_irq_set], r600_irq_set: sw int                                            
Jan 17 16:39:49 segfault kernel: <m_ioctl], pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1                
Jan 17 16:39:49 segfault kernel: <8646a, nr=0x6a, dev 0xe200, auth=1                                             
Jan 17 16:39:50 segfault kernel: <7drm: 0xe200, auth=1                                                           
Jan 17 16:39:50 segfault kernel: pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1                           
Jan 17 16:39:50 segfault kernel: [  102.164462] r600_irq_process start: rptr 13344, wptr 13360                   
Jan 17 16:39:50 segfault kernel: [  102.181369] pid=3277, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:50 segfault kernel: <7] [ddev 0xe200, auth=1                                                        
Jan 17 16:39:50 segfault kernel: xc008646a, nr=0x6a, dev 0xe200, auth=1                                          
Jan 17 16:39:50 segfault kernel: [  102.281385] IH: D1 vblank                                                    
Jan 17 16:39:50 segfault kernel: [  102.309757] r600_irq_process start: rptr 17952, wptr 17968                   
Jan 17 16:39:50 segfault kernel: <7 [drm:drm_ioctl], pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1       
Jan 17 16:39:50 segfault kernel: <drm: 0xe200, auth=1                                                            
Jan 17 16:39:50 segfault kernel: <drm: 0xe200, auth=1                                                            
Jan 17 16:39:50 segfault kernel: <7rm:d0xe200, auth=1                                                            
Jan 17 16:39:50 segfault kernel: [  102.431381] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:50 segfault kernel: [  102.431385] IH: D1 vblank                                                    
Jan 17 16:39:50 segfault kernel: [  102.496953] pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:50 segfault kernel: < cmd=0x40086409, nr=0x09, dev 0xe200, auth=1                                   
Jan 17 16:39:50 segfault kernel: < vblank 0                                                                      
Jan 17 16:39:50 segfault kernel: <pid=6464, nr=0x64, dev 0xe200, auth=1                                          
Jan 17 16:39:50 segfault kernel: [  102.631397] pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:50 segfault kernel: <7[drnk 17] [drm:r600_irq_set], r600_irq_set: hpd 1                             
Jan 17 16:39:50 segfault kernel: [  102.757470] x 282 y 289 c->x 0 c->y 0                                        
Jan 17 16:39:50 segfault kernel: , r61                                                                           
Jan 17 16:39:50 segfault kernel: <, r61                                                                          
Jan 17 16:39:50 segfault kernel: <, r63                                                                          
Jan 17 16:39:50 segfault kernel: <7rq_set: vblank 1                                                              
Jan 17 16:39:50 segfault kernel: l], ret = fffffff0                                                              
Jan 17 16:39:50 segfault kernel: xc0086464, nr=0x64, dev 0xe200, auth=1                                          
Jan 17 16:39:50 segfault kernel: <76464, nr=0x64, dev 0xe200, auth=1                                             
Jan 17 16:39:50 segfault kernel: xc008646a, nr=0x6a, dev 0xe200, auth=1                                          
Jan 17 16:39:50 segfault kernel: <drm: 0xe200, auth=1                                                            
Jan 17 16:39:51 segfault kernel: 0788rptr 42336, wptr 42352                                                      
Jan 17 16:39:51 segfault kernel: < vblank 0                                                                      
Jan 17 16:39:51 segfault kernel: , ret = fffffff0                                                                
Jan 17 16:39:51 segfault kernel: [  103.148146] r600_irq_process start: rptr 44208, wptr 44224                   
Jan 17 16:39:51 segfault kernel: <7006] [drm:r6rq_p011] [drm:r600_irq_process], IH: CP int: 0x00000000           
Jan 17 16:39:51 segfault kernel: [  103.164760] r600_irq_set: vblank 0                                           
Jan 17 16:39:51 segfault kernel: [  103.164766] r600_irq_set: hpd 1                                              
Jan 17 16:39:51 segfault kernel: <646a, nr=0x6a, dev 0xe200, auth=1                                              
Jan 17 16:39:51 segfault kernel: <7 nr=0x64, dev 0xe200, auth=1                                                  
Jan 17 16:39:51 segfault kernel: <:drm_ioctl], ret = fffffff0                                                    
Jan 17 16:39:51 segfault kernel: xc020645e, nr=0x5e, dev 0xe200, auth=1                                          
Jan 17 16:39:51 segfault kernel: <103.271472] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                                                                              
Jan 17 16:39:51 segfault kernel: <x64, dev 0xe200, auth=1                                                        
Jan 17 16:39:51 segfault kernel: <et =103.343119] [drm:drm_ioctl], pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1                                                                                                          
Jan 17 16:39:51 segfault kernel: <7nk 1                                                                          
Jan 17 16:39:51 segfault kernel: ] [drm:r600_irq_set], r600_irq_set: vblank 0                                    
Jan 17 16:39:51 segfault kernel: 43] [drm:r600_irq_set], r600_irq_set: hpd 3                                     
Jan 17 16:39:51 segfault kernel: <rm:r600_irq_set], r600_irq_set: hpd 1                                          
Jan 17 16:39:51 segfault kernel: <, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1                                  
Jan 17 16:39:51 segfault kernel: <7  103.52713277  103.527160] [drm:drm_ioctl], pid=3277, cmd=0xc0086464, nr=0x64, de<7>[06466, nr=0x66, dev 0xe200, auth=1                                                                       
Jan 17 16:39:51 segfault kernel: [  103.548120] r600_irq_process start: rptr 58800, wptr 58816                   
Jan 17 16:39:51 segfault kernel: [  103.582342] pid=2793, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1            
Jan 17 16:39:51 segfault kernel: <7, IH: D1 vblank                                                               
Jan 17 16:39:51 segfault kernel: nt: 0x00000000                                                                  
Jan 17 16:39:51 segfault kernel: <7020645e, nr=0x5e, dev 0xe200, auth=1                                          
Jan 17 16:39:51 segfault kernel: <7t                                                                             
Jan 17 16:39:51 segfault kernel: <:r600_irq_set], r600_irq_set: hpd 3                                            
Jan 17 16:39:51 segfault kernel: <dev 0xe200, auth=1                                                             
Jan 17 16:39:51 segfault kernel: < sw int                                                                        
Jan 17 16:39:51 segfault kernel: ] [d.864663] [drm:r600_irq_set], r600_irq_set: hpd 1                            
Jan 17 16:39:51 segfault kernel: <0x00000000                                                                     
Jan 17 16:39:52 segfault kernel: [  104.121883] r600_irq_process start: rptr 5120, wptr 5136                     
Jan 17 16:39:52 segfault kernel: <7hpd 3                                                                         
Jan 17 16:39:52 segfault kernel: <7, r60nk et],blank 1                                                           
Jan 17 16:39:52 segfault kernel: <process start: rptr 11424, wptr 11440                                          
Jan 17 16:39:52 segfault kernel: [  104.364847] IH: D1 vblank                                                    
Jan 17 16:39:52 segfault kernel: <rm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1               
Jan 17 16:39:52 segfault kernel: <7_ioctl], pid=3277, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1                
Jan 17 16:39:52 segfault kernel: <:r60rm:drm_ioctl], pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1] [d31645] [drm:r600_irq_set], r600_irq_set: vblank 0                                                                   
Jan 17 16:39:52 segfault kernel: <process start: rptr 15696, wptr 15712                                          
Jan 17 16:39:52 segfault kernel: , IH: CP int: 0x00000000                                                        
Jan 17 16:39:52 segfault kernel: <r600_irq_set: hpd 3                                                            
Jan 17 16:39:52 segfault kernel: <89567] [drm:r600_irq_set], r600_irq_set: hpd 1                                 
Jan 17 16:39:52 segfault kernel: 104.513828] [drm:drm_ioctl], ret = fffffff0                                     
Jan 17 16:39:52 segfault kernel: <7m:r602] [drm:r600_irq_set], r600_irq_set: hpd 1                               
Jan 17 16:39:52 segfault kernel: [  104.664866] ret = fffffff0                                                   
Jan 17 16:39:52 segfault kernel: <7rm:d0xe200, auth=1                                                            
Jan 17 16:39:52 segfault kernel: <rm:d0xe200, auth=1                                                             
Jan 17 16:39:52 segfault kernel: [  104.764872] r600_irq_set: vblank 0                                           
Jan 17 16:39:52 segfault kernel: <7[drm104._ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1        
Jan 17 16:39:52 segfault kernel: [  104.898231] r600_irq_process start: rptr 31920, wptr 31936                   
Jan 17 16:39:52 segfault kernel: [  104.914881] IH: D1 vblank                                                    
Jan 17 16:39:52 segfault kernel: <7m_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1               
Jan 17 16:39:53 segfault kernel: 105.097119] [drm:r600_irq_set], r600_irq_set:d=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1                                                                                                 
Jan 17 16:39:53 segfault kernel: <7106] [drm:r600_irq_set], r600_irq_set: hpd 3                                  
Jan 17 16:39:53 segfault kernel: < [dr105.122469] [drm:r600_irq_set], r600_irq_set: vblank 1                     
Jan 17 16:39:53 segfault kernel: < [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1        
Jan 17 16:39:53 segfault kernel: 05.220472] [drm:drm_ioctl], ret = fffffff0                                      
Jan 17 16:39:53 segfault kernel: <5.232039] [drm:r600_irq_set], r600_irq_set: vblank 1                           
Jan 17 16:39:53 segfault kernel: <], r>[  105.270564] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                                                                      
Jan 17 16:39:53 segfault kernel: <7pd 3                                                                          
Jan 17 16:39:53 segfault kernel: <70086464, nr=0x64, dev 0xe200, auth=1                                          
Jan 17 16:39:53 segfault kernel: <7rm:d0xe200, auth=1                                                            
Jan 17 16:39:53 segfault kernel: <7[drm:drm_ioctl], pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1        
Jan 17 16:39:53 segfault kernel: <ioct auth=1                                                                    
Jan 17 16:39:53 segfault kernel: <7 105.505299] [drm:drm_ioctl], pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1                                                                                                            
Jan 17 16:39:53 segfault kernel: r600_irq_set: hpd 1                                                             
Jan 17 16:39:53 segfault kernel: 6] [drm:drm_ioctl], pid=3277, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1       
Jan 17 16:39:53 segfault kernel: <.669727] [drm:drm_ioctl], ret = fffffff0                                       
Jan 17 16:39:53 segfault kernel: <et: hpd 1                                                                      
Jan 17 16:39:53 segfault kernel: irq_process], r600_irq_process start: rptr 59184, wptr 59200                    
Jan 17 16:39:53 segfault kernel: <7<7>[  105.747302] [drm:drm_ioctl], pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1                                                                                                       
Jan 17 16:39:53 segfault kernel: 77620x5d, dev 0xe200, auth=1                                                    
Jan 17 16:39:53 segfault kernel: xc008646a, nr=0x6a, dev 0xe200, auth=1                                          
Jan 17 16:39:53 segfault kernel: <7ff0                                                                           
Jan 17 16:39:54 segfault kernel: <6.155542] [drm:drm_ioctl], pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1                                                                                                                
Jan 17 16:39:54 segfault kernel: [  106.275584] r600_irq_process start: rptr 9680, wptr 9696                     
Jan 17 16:39:54 segfault kernel: [  106.423258] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:54 segfault kernel: [  106.802974] r600_irq_process start: rptr 12592, wptr 12608                   
Jan 17 16:39:54 segfault kernel: [  106.887158] r600_irq_set: hpd 1                                              
Jan 17 16:39:54 segfault kernel: [  107.070009] r600_irq_set: vblank 1                                           
Jan 17 16:39:55 segfault kernel: [  107.221445] r600_irq_set: vblank 1                                           
Jan 17 16:39:55 segfault kernel: [  107.620129] IH: CP int: 0x00000000                                           
Jan 17 16:39:55 segfault kernel: [  107.877891] r600_irq_process start: rptr 17744, wptr 17760                   
Jan 17 16:39:55 segfault kernel: [  107.910716] pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.118513] pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.874494] pid=2793, cmd=0xc01c64a3, nr=0xa3, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.892968] pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.892998] pid=3277, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.906243] pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.939347] r600_irq_set: sw int                                             
Jan 17 16:39:56 segfault kernel: [  108.948709] pid=2793, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.961436] pid=3277, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.961665] pid=2793, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.988611] pid=3277, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.988841] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.995486] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  108.995697] r600_irq_set: vblank 1                                           
Jan 17 16:39:56 segfault kernel: [  109.000798] r600_irq_set: vblank 1                                           
Jan 17 16:39:56 segfault kernel: [  109.025132] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  109.033111] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  109.074816] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:56 segfault kernel: [  109.096360] pid=3277, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1            
Jan 17 16:39:57 segfault kernel: [  109.573489] pid=2793, cmd=0xc01c64a3, nr=0xa3, dev 0xe200, auth=1            
Jan 17 16:39:57 segfault kernel: [  109.628989] pid=3277, cmd=0xc020645e, nr=0x5e, dev 0xe200, auth=1            
Jan 17 16:39:57 segfault kernel: [  109.674340] ret = fffffff0                                                   
Jan 17 16:39:57 segfault kernel: [  109.677506] r600_irq_process start: rptr 24208, wptr 24224                   
Jan 17 16:39:57 segfault kernel: [  109.683036] r600_irq_set: hpd 3                                              
Jan 17 16:39:57 segfault kernel: [  109.783823] r600_irq_process start: rptr 24624, wptr 24640                   
Jan 17 16:39:57 segfault kernel: [  109.803752] pid=2793, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.394741] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.434339] r600_irq_set: vblank 1                                           
Jan 17 16:39:58 segfault kernel: [  110.485217] r600_irq_set: vblank 1                                           
Jan 17 16:39:58 segfault kernel: [  110.625647] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.625659] pid=3277, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.625679] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.698779] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.702479] r600_irq_set: hpd 1                                              
Jan 17 16:39:58 segfault kernel: [  110.709424] IH: CP int: 0x00000000                                           
Jan 17 16:39:58 segfault kernel: [  110.731462] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1            
Jan 17 16:39:58 segfault kernel: [  110.760552] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1
Jan 17 16:39:58 segfault kernel: [  110.808552] pid=2793, cmd=0xc008646a, nr=0x6a, dev 0xe200, auth=1
Jan 17 16:39:58 segfault kernel: [  110.830677] pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1
Jan 17 16:39:58 segfault kernel: [  110.893443] x 736 y 0 c->x 0 c->y 0
Jan 17 16:39:58 segfault kernel: [  110.893461] r600_irq_set: hpd 1
Jan 17 16:39:58 segfault kernel: [  110.893467] r600_irq_set: hpd 3
Jan 17 16:39:58 segfault kernel: [  110.984902] r600_irq_process start: rptr 29552, wptr 29568
Jan 17 16:39:59 segfault kernel: [  111.469429] pid=3277, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1
Jan 17 16:39:59 segfault kernel: [  112.085477] r600_irq_process start: rptr 33440, wptr 33456
Jan 17 16:40:00 segfault kernel: [  112.109665] r600_irq_set: hpd 1
Jan 17 16:40:00 segfault kernel: [  112.181247] pid=3277, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  112.181297] pid=3277, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  112.217968] r600_irq_process start: rptr 33968, wptr 33984
Jan 17 16:40:00 segfault kernel: [  112.263044] r600_irq_set: vblank 1
Jan 17 16:40:00 segfault kernel: [  112.268440] r600_irq_process start: rptr 34176, wptr 34192
Jan 17 16:40:00 segfault kernel: [  112.313000] r600_irq_process start: rptr 34384, wptr 34400
Jan 17 16:40:00 segfault kernel: [  112.376031] pid=2793, cmd=0x40086409, nr=0x09, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  112.376434] pid=3277, cmd=0xc0206466, nr=0x66, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  112.478413] r600_irq_process start: rptr 35040, wptr 35056
Jan 17 16:40:00 segfault kernel: [  112.925856] r600_irq_set: hpd 1
Jan 17 16:40:00 segfault kernel: [  112.976137] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  113.024870] r600_irq_process start: rptr 36992, wptr 37008
Jan 17 16:40:00 segfault kernel: [  113.061963] r600_irq_process start: rptr 37168, wptr 37184
Jan 17 16:40:00 segfault kernel: [  113.091047] pid=3277, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1
Jan 17 16:40:00 segfault kernel: [  113.091466] r600_irq_set: vblank 1
Jan 17 16:40:01 segfault kernel: [  113.153094] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1
Jan 17 16:40:01 segfault kernel: [  113.162379] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1
Jan 17 16:40:01 segfault kernel: [  113.181603] pid=2793, cmd=0xc020645d, nr=0x5d, dev 0xe200, auth=1
Jan 17 16:40:01 segfault kernel: [  113.235529] pid=2793, cmd=0xc0086464, nr=0x64, dev 0xe200, auth=1
Jan 17 16:40:01 segfault kernel: [  113.326375] IH: CP int: 0x00000000
Jan 17 16:40:01 segfault kernel: [  113.375745] IH: CP int: 0x00000000
Jan 17 16:40:01 segfault kernel: [  113.477498] r600_irq_process start: rptr 39056, wptr 39072
Comment 1 Shawn Starr 2010-01-17 14:04:36 UTC 
Please see log file here, when using Second Life you can see the full RV635 DRM conversation output:

http://www.sh0n.net/spstarr/radeon/rv635.debug.bz2

This pretty much shows from boot til the time I started Second Life and then exited.
Comment 2 Shawn Starr 2010-01-17 16:46:15 UTC 
I can reproduce this on two RV635 GPUs one on laptop one in desktop PCIe form.

Laptop GPU info:

PCI ID: 1002:9591
01:00.0 VGA compatible controller: ATI Technologies Inc Mobility Radeon HD 3650 (prog-if 00 [VGA controller])
        Subsystem: Lenovo Device 2127                                                                        
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- 
        Latency: 0, Cache Line Size: 64 bytes                                                                
        Interrupt: pin A routed to IRQ 16                                                                    
        Region 0: Memory at d0000000 (32-bit, prefetchable) [size=256M]                                      
        Region 1: I/O ports at 2000 [size=256]                                                               
        Region 2: Memory at cfff0000 (32-bit, non-prefetchable) [size=64K]                                   
        [virtual] Expansion ROM at cff00000 [disabled] [size=128K]                                           
        Capabilities: <access denied>

Desktop GPU Info:

PCI ID: 1002:9598
05:00.0 VGA compatible controller: ATI Technologies Inc Mobility Radeon HD 3600 Series (prog-if 00 [VGA controller])                                                                                                            
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+   
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-    
        Latency: 0, Cache Line Size: 32 bytes                                                                   
        Interrupt: pin A routed to IRQ 32                                                                       
        Region 0: Memory at d0000000 (64-bit, prefetchable) [size=256M]                                         
        Region 2: Memory at fe8e0000 (64-bit, non-prefetchable) [size=64K]                                      
        Region 4: I/O ports at d000 [size=256]                                                                  
        Expansion ROM at fe8c0000 [disabled] [size=128K]                                                        
        Capabilities: [50] Power Management version 3                                                           
                Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)                      
                Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-                                          
        Capabilities: [58] Express (v2) Legacy Endpoint, MSI 00                                                 
                DevCap: MaxPayload 128 bytes, PhantFunc 0, Latency L0s <4us, L1 unlimited                       
                        ExtTag+ AttnBtn- AttnInd- PwrInd- RBE+ FLReset-                                         
                DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported-                              
                        RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop+                                            
                        MaxPayload 128 bytes, MaxReadReq 128 bytes                                              
                DevSta: CorrErr- UncorrErr- FatalErr- UnsuppReq- AuxPwr- TransPend-                             
                LnkCap: Port #0, Speed 2.5GT/s, Width x16, ASPM L0s L1, Latency L0 <64ns, L1 <1us               
                        ClockPM- Surprise- LLActRep- BwNot-                                                     
                LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- Retrain- CommClk+                                 
                        ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt-                                          
                LnkSta: Speed 2.5GT/s, Width x16, TrErr- Train- SlotClk+ DLActive- BWMgmt- ABWMgmt-             
                DevCap2: Completion Timeout: Not Supported, TimeoutDis-                                         
                DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-                                          
                LnkCtl2: Target Link Speed: 2.5GT/s, EnterCompliance- SpeedDis-, Selectable De-emphasis: -6dB   
                         Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS-       
                         Compliance De-emphasis: -6dB                                                           
                LnkSta2: Current De-emphasis Level: -6dB                                                        
        Capabilities: [a0] MSI: Enable+ Count=1/1 Maskable- 64bit+                                              
                Address: 00000000fee0f00c  Data: 41c1                                                           
        Capabilities: [100] Vendor Specific Information <?>                                                     
        Kernel driver in use: radeon
Comment 3 Shawn Starr 2010-01-17 16:55:00 UTC 
Full Laptop GPU info:

01:00.0 VGA compatible controller: ATI Technologies Inc Mobility Radeon HD 3650 (prog-if 00 [VGA controller])
        Subsystem: Lenovo Device 2127                                                                        
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- 
        Latency: 0, Cache Line Size: 64 bytes                                                                
        Interrupt: pin A routed to IRQ 16                                                                    
        Region 0: Memory at d0000000 (32-bit, prefetchable) [size=256M]                                      
        Region 1: I/O ports at 2000 [size=256]                                                               
        Region 2: Memory at cfff0000 (32-bit, non-prefetchable) [size=64K]                                   
        [virtual] Expansion ROM at cff00000 [disabled] [size=128K]                                           
        Capabilities: [50] Power Management version 3                                                        
                Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)                   
                Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-                                       
        Capabilities: [58] Express (v2) Legacy Endpoint, MSI 00                                              
                DevCap: MaxPayload 128 bytes, PhantFunc 0, Latency L0s <4us, L1 unlimited                    
                        ExtTag+ AttnBtn- AttnInd- PwrInd- RBE+ FLReset-                                      
                DevCtl: Report errors: Correctable- Non-Fatal- Fatal- Unsupported-                           
                        RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop+                                         
                        MaxPayload 128 bytes, MaxReadReq 128 bytes                                           
                DevSta: CorrErr+ UncorrErr- FatalErr- UnsuppReq- AuxPwr- TransPend-                          
                LnkCap: Port #0, Speed 2.5GT/s, Width x16, ASPM L0s L1, Latency L0 <64ns, L1 <1us            
                        ClockPM- Surprise- LLActRep- BwNot-                                                  
                LnkCtl: ASPM L0s L1 Enabled; RCB 64 bytes Disabled- Retrain- CommClk+                        
                        ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt-                                       
                LnkSta: Speed 2.5GT/s, Width x16, TrErr- Train- SlotClk+ DLActive- BWMgmt- ABWMgmt-          
                DevCap2: Completion Timeout: Not Supported, TimeoutDis-                                      
                DevCtl2: Completion Timeout: 50us to 50ms, TimeoutDis-                                       
                LnkCtl2: Target Link Speed: 2.5GT/s, EnterCompliance- SpeedDis-, Selectable De-emphasis: -6dB
                         Transmit Margin: Normal Operating Range, EnterModifiedCompliance- ComplianceSOS-    
                         Compliance De-emphasis: -6dB                                                        
                LnkSta2: Current De-emphasis Level: -3.5dB                                                   
        Capabilities: [a0] MSI: Enable- Count=1/1 Maskable- 64bit+                                           
                Address: 0000000000000000  Data: 0000                                                        
        Capabilities: [100] Vendor Specific Information <?>
Comment 4 Jerome Glisse 2010-01-18 05:30:02 UTC 
Created attachment 32691 [details] [review]
DEBUG info for r6xx irq

Please test the attached patch, reboot with drm.debug=0 activate kms, log into X and do whatever is trigger the stall, wait 5sec, and do from a terminal: dmesg > dmesg.txt and attach it to the bug thanks.
Comment 5 Shawn Starr 2010-01-18 06:06:12 UTC 
Created attachment 32692 [details]
r635 dmesg with patch debug output

r635 dmesg with patch debug output
Comment 6 Jerome Glisse 2010-01-18 06:58:11 UTC 
Created attachment 32694 [details] [review]
Avoid irq contention ?

Please try if this patch helps.
Comment 7 Shawn Starr 2010-01-25 13:54:48 UTC 
I can reproduce this with 2.6.32.6 with KMS that is pre-IRQ. There are stalls happening here.

We can remove IRQs from the equation.
Comment 8 Shawn Starr 2010-01-25 13:56:47 UTC 
Resolution of screen is: 1920 x 1200

XRandr info:
Screen 0: minimum 320 x 200, current 1920 x 1200, maximum 8192 x 8192
DVI-0 disconnected (normal left inverted right x axis y axis)        
LVDS connected 1920x1200+0+0 (normal left inverted right x axis y axis) 331mm x 207mm
   1920x1200      60.0*+   49.8
   1920x1080      60.0
   1600x1200      59.9
   1680x1050      60.0
   1400x1050      60.0
   1280x1024      59.9
   1440x900       59.9
   1280x960       59.9
   1280x854       59.9
   1280x800       59.8
   1280x720       59.9
   1152x768       59.8
   1024x768       59.9
   800x600        59.9
   848x480        59.7
   720x480        59.7
   640x480        59.4
DP disconnected (normal left inverted right x axis y axis)
VGA-0 disconnected (normal left inverted right x axis y axis)
Comment 9 Shawn Starr 2010-02-02 01:45:50 UTC 
Created attachment 32993 [details]
Kernel configuration I use
Comment 10 Jerome Glisse 2010-02-08 02:52:28 UTC 
Some lock statistics
http://people.freedesktop.org/~suokko/stalls_locl_stats
Comment 11 Pauli 2010-02-08 03:33:13 UTC 
Ok. I found out what is shown there now.

Documentation/lockstat.txt for explanation of all the fields.

Time values are in micro seconds. Read from shed_clock that returns
nanoseconds and in output divided by 1k.

So any value that is more than few thousands in hold time is causing
some user visible stalling if contented.

> --- Comment #10 from Jerome Glisse <glisse@freedesktop.org>  2010-02-08 02:52:28 PST ---
> Some lock statistics
> http://people.freedesktop.org/~suokko/stalls_locl_stats
>
>
Comment 12 Shawn Starr 2010-02-08 14:53:06 UTC 
Created attachment 33182 [details]
Lock stats over 10 minutes running game
Comment 13 Ming-Wei Shih 2010-02-09 05:59:54 UTC 
Created attachment 33193 [details]
.config

I am having stall issues too for some time, it's getting worse (more frequent stalls and last longer. When running glx-gears (with kde4 composite on) and move the mouse around a bit I get this according to latencytop

radeon_ring_lock radeon_ib_schedule radeon_cs_ioct195.2 msec          0.9 %

Attached is my .config.
Comment 14 Ming-Wei Shih 2010-02-09 06:02:59 UTC 
more about my setup,

I am using kms with irq.

kernel: drm-radeon-testing
mesa: git
ddx: git
libdrm: git

00:0b.0 PCI bridge: nVidia Corporation nForce3 250Gb AGP Host to PCI Bridge (rev a2)
01:00.0 VGA compatible controller: ATI Technologies Inc RV670 AGP [Radeon HD 3850]

CPU: model name      : AMD Athlon(tm) 64 X2 Dual Core Processor 3800+
Comment 15 Shawn Starr 2010-12-04 22:38:01 UTC 
Close it, wait for r600 gallium driver

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.