XPost: linux.debian.bugs.dist, linux.kernel   
   From: TWR@tylerwross.com   
      
   Thanks, Chunk.   
      
   Suggested trace-cmd report from the client follows. Last 3 lines appear   
   salient, but I've included the full report just in case.   
      
   cpus=4   
                 ls-969   [003] .....   270.318649: nfs_getattr_enter:      
   fileid=00:2d:262146 fhandle=0xad8c294c version=31 cache_validity=0x0 ()   
                 ls-969   [003] .....   270.318651: nfs_getattr_exit:     error=0   
   (OK) fileid=00:2d:262146 fhandle=0xad8c294c type=4 (DIR) version=31 size=4096   
   cache_validity=0x0 () nfs_flags=0x0 ()   
                 ls-969   [003] .....   270.318654: nfs_revalidate_inode_enter:   
   fileid=00:2d:262146 fhandle=0xad8c294c version=31 cache_validity=0x0 ()   
                 ls-969   [003] .....   270.318658: rpc_task_begin:         
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF runstate=0x4   
   status=0 action=0x0   
                 ls-969   [003] .....   270.318658: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=rpc_prepare_task   
                 ls-969   [003] .....   270.318660: nfs4_setup_sequence:    
   session=0x5988ad3c slot_nr=0 seq_nr=24 highest_used_slotid=0   
                 ls-969   [003] .....   270.318661: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_start   
                 ls-969   [003] .....   270.318661: rpc_request:            
   task:00000006@00000005 nfsv4 GETATTR (sync)   
                 ls-969   [003] .....   270.318662: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_reserve   
                 ls-969   [003] .....   270.318663: xprt_reserve:           
   task:00000006@00000005 xid=0x79569c7a   
                 ls-969   [003] .....   270.318663: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_reserveresult   
                 ls-969   [003] .....   270.318663: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_refresh   
           rpc.gssd-613   [001] .....   270.318690: rpcgss_upcall_msg:      
   msg='mech=krb5 uid=591200003 enctypes=20,19,18,17'   
           rpc.gssd-970   [002] .....   270.326582: rpcgss_context:         
   win_size=128 expiry=4316009978 now=4294959728 timeout=84201 acce   
   tor=nfs@nfssrv.ipa.twrlab.net   
                 ls-969   [003] ...1.   270.326598: rpcgss_ctx_init:        
   cred=0xffff8895c5989900 service=integrity principal='(null)'   
                 ls-969   [003] .....   270.326600: rpcgss_upcall_result: for uid   
   591200003, result=0   
                 ls-969   [003] .....   270.326601: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_refreshresult   
                 ls-969   [003] .....   270.326601: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_allocate   
                 ls-969   [003] .....   270.326603: rpc_buf_alloc:          
   task:00000006@00000005 callsize=1844 recvsize=2704 status=0   
                 ls-969   [003] .....   270.326603: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x4 status=0 action=call_encode   
                 ls-969   [003] .....   270.326604: rpcgss_seqno:           
   task:00000006@00000005 xid=0x79569c7a seqno=1   
                 ls-969   [003] .....   270.326611: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|NORTO|CRED_NOREF r   
   nstate=RUNNING|0x1c status=0 action=call_transmit   
                 ls-969   [003] ...1.   270.326611: xprt_reserve_xprt:      
   task:00000006@00000005 snd_task:00000006   
                 ls-969   [003] .....   270.326612: rpcgss_need_reencode:   
   task:00000006@00000005 xid=0x79569c7a rq_seqno=1 seq_xmit=0 reencode unneeded   
                 ls-969   [003] .....   270.326612: rpc_xdr_sendto:         
   task:00000006@00000005 head=[0xffff8895c29fe008,260] page=0(0) tail=[(nil),0]   
   len=260   
                 ls-969   [003] .....   270.326627: xprt_transmit:          
   task:00000006@00000005 xid=0x79569c7a seqno=1 status=0   
                 ls-969   [003] ...1.   270.326628: xprt_release_xprt:      
   task:00000006@00000005 snd_task:ffffffff   
                 ls-969   [003] .....   270.326629: rpc_task_run_action:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|SENT|NORTO|CRED_NOREF   
   runstate=RUNNING|0x14 status=0 action=call_transmit_status   
                 ls-969   [003] ...2.   270.326629: rpc_task_sleep:         
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|SENT|NORTO|CRED_NOREF   
   runstate=RUNNING|0x14 status=0 timeout=0 queue=xprt_pending   
                 ls-969   [003] .....   270.326630: rpc_task_sync_sleep:    
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|SENT|NORTO|CRED_NOREF   
   runstate=0x16 status=0 action=call_status   
             -0     [001] ..s2.   270.326754: xs_data_ready:          
   peer=[10.108.2.102]:2049   
      kworker/u16:0-12    [001] ...1.   270.326762: xprt_lookup_rqst:       
   peer=[10.108.2.102]:2049 xid=0x79569c7a status=0   
      kworker/u16:0-12    [001] ...2.   270.326764: rpc_task_wakeup:        
   task:00000006@00000005 flags=MOVEABLE|DYNAMIC|SENT|NORTO|CRED_NOREF   
   runstate=0x6 status=0 timeout=15000 queue=xprt_pending   
      
   [continued in next message]   
      
   --- SoupGate-Win32 v1.05   
    * Origin: you cannot sedate... all the things you hate (1:229/2)