http://bugzilla.novell.com/show_bug.cgi?id=557760
http://bugzilla.novell.com/show_bug.cgi?id=557760#c2
--- Comment #2 from Jon Nelson 2009-12-01 02:44:54 UTC ---
I'll sent what I can, however some trimming might be in order.
I have 10,000 lines here.
Perhaps this is useful. The following snippet appears *thousands* of times,
repeating *exactly* with only the "now" values updating:
lines 6687 through 9926 or thereabouts:
Nov 23 09:11:07 frank kernel: [ 3855.686461] RPC: worker connecting xprt
ffff88002c45c000 to address: addr=192.168.2.1 port=2049 proto=tcp
Nov 23 09:11:07 frank kernel: [ 3855.686469] RPC: ffff88002c45c000
connect status 99 connected 0 sock state 7
Nov 23 09:11:07 frank kernel: [ 3855.686474] RPC: 16303 __rpc_wake_up_task (now
4295856217)
Nov 23 09:11:07 frank kernel: [ 3855.686478] RPC: 16303 disabling timer
Nov 23 09:11:07 frank kernel: [ 3855.686483] RPC: 16303 removed from queue
ffff88002c45c2f0 "xprt_pending"
Nov 23 09:11:07 frank kernel: [ 3855.686487] RPC: __rpc_wake_up_task done
Nov 23 09:11:07 frank kernel: [ 3855.686492] RPC: 16303 __rpc_execute flags=0x1
Nov 23 09:11:07 frank kernel: [ 3855.686495] RPC: 16303 xprt_connect_status:
retrying
Nov 23 09:11:07 frank kernel: [ 3855.686500] RPC: 16303 call_connect_status
(status -11)
Nov 23 09:11:07 frank kernel: [ 3855.686504] RPC: 16303 call_transmit (status
0)
Nov 23 09:11:07 frank kernel: [ 3855.686508] RPC: 16303 xprt_prepare_transmit
Nov 23 09:11:07 frank kernel: [ 3855.686512] RPC: 16303 rpc_xdr_encode (status
0)
Nov 23 09:11:07 frank kernel: [ 3855.686517] RPC: 16303 marshaling UNIX cred
ffff88002c710a40
Nov 23 09:11:07 frank kernel: [ 3855.686522] RPC: 16303 using AUTH_UNIX cred
ffff88002c710a40 to wrap rpc data
Nov 23 09:11:07 frank kernel: [ 3855.686527] RPC: 16303 xprt_transmit(131252)
Nov 23 09:11:07 frank kernel: [ 3855.686532] RPC:
xs_tcp_send_request(131252) = -32
Nov 23 09:11:07 frank kernel: [ 3855.686536] RPC: xs_tcp_state_change
client ffff88002c45c000...
Nov 23 09:11:07 frank kernel: [ 3855.686541] RPC: state 7 conn 0 dead 0
zapped 1
Nov 23 09:11:07 frank kernel: [ 3855.686545] RPC: disconnected transport
ffff88002c45c000
Nov 23 09:11:07 frank kernel: [ 3855.686551] RPC: 16303 call_status (status
-32)
Nov 23 09:11:07 frank kernel: [ 3855.686555] RPC: 16303 call_bind (status 0)
Nov 23 09:11:07 frank kernel: [ 3855.686559] RPC: 16303 call_connect xprt
ffff88002c45c000 is not connected
Nov 23 09:11:07 frank kernel: [ 3855.686564] RPC: 16303 xprt_connect xprt
ffff88002c45c000 is not connected
Nov 23 09:11:08 frank kernel: [ 3855.686570] RPC: 16303 sleep_on(queue
"xprt_pending" time 4295856217)
Nov 23 09:11:08 frank kernel: [ 3855.686575] RPC: 16303 added to queue
ffff88002c45c2f0 "xprt_pending"
Nov 23 09:11:08 frank kernel: [ 3855.686580] RPC: 16303 setting alarm for 60000
ms
Nov 23 09:11:08 frank kernel: [ 3855.686584] RPC: xs_connect delayed xprt
ffff88002c45c000 for 0 seconds
what follows are these lines:
Nov 23 09:12:27 frank kernel: [ 3855.702614] RPC: worker connecting xprt
ffff88002c45c000 to address: addr=192.168.2.1 port=2049 proto=tcp
Nov 23 09:12:27 frank kernel: [ 3855.702622] RPC: ffff88002c45c000
connect status 99 connected 0 sock state 7
Nov 23 09:12:27 frank kernel: [ 3855.702628] RPC: 16303 __rpc_wake_up_task (now
4295856221)
Nov 23 09:12:27 frank kernel: [ 3855.702632] RPC: 16303 disabling timer
Nov 23 09:12:27 frank kernel: [ 3855.702636] RPC: 16303 removed from queue
ffff88002c45c2f0 "xprt_pending"
Nov 23 09:12:27 frank kernel: [ 3855.702641] RPC: __rpc_wake_up_task done
Nov 23 09:12:27 frank kernel: [ 3855.702645] RPC: 16303 __rpc_execute flags=0x1
Nov 23 09:12:27 frank kernel: [ 3855.702649] RPC: 16303 xprt_connect_status:
retrying
Nov 23 09:12:27 frank kernel: [ 3855.702653] RPC: 16303 call_connect_status
(status -11)
Nov 23 09:12:27 frank kernel: [ 3855.702672] RPC: 16303 call_transmit (status
0)
Nov 23 09:12:27 frank kernel: [ 3855.702676] RPC: 16303 xprt_prepare_transmit
Nov 23 09:12:27 frank kernel: [ 3855.702680] RPC: 16303 rpc_xdr_encode (status
0)
Nov 23 09:12:27 frank kernel: [ 3855.702685] RPC: 16303 marshaling UNIX cred
ffff88002c710a40
Nov 23 09:12:27 frank kernel: [ 3855.702690] RPC: 16303 using AUTH_UNIX cred
ffff88002c710a40 to wrap rpc data
Nov 23 09:12:27 frank kernel: [ 3855.702695] RPC: 16303 xprt_transmit(131252)
Nov 23 09:12:27 frank kernel: [ 3855.702700] RPC:
xs_tcp_send_request(131252) = -32
Nov 23 09:12:27 frank kernel: [ 3855.702705] RPC: xs_tcp_state_change
client ffff88002c45c000...
Nov 23 09:12:27 frank kernel: [ 3855.702710] RPC: state 7 conn 0 dead 0
zapped 1
Nov 23 09:12:27 frank kernel: [ 3855.702714] RPC: disconnected transport
ffff88002c45c000
Nov 23 09:12:27 frank kernel: [ 3855.702724] -pid- flgs status -client-
--rqstp- -timeout ---ops--
Nov 23 09:12:27 frank kernel: [ 3855.702779] 15517 0001 -11 ffff88002f5dd200
(null) 0 ffffffffa035c500 nfsv4 WRITE a:call_reserveresult
q:xprt_backlog
Nov 23 09:12:27 frank kernel: [ 3855.702826] 15906 0001 -11 ffff88002f5dd200
ffff880025594930 0 ffffffffa035c500 nfsv4 WRITE a:call_status
q:xprt_resend
Nov 23 09:12:27 frank kernel: [ 3855.702863] 15907 0001 -11 ffff88002f5dd200
ffff8800255943f0 0 ffffffffa035c500 nfsv4 WRITE a:call_status
q:xprt_resend
Nov 23 09:12:27 frank kernel: [ 3855.702897] 15908 0001 -11 ffff88002f5dd200
ffff880025594fc0 0 ffffffffa035c500 nfsv4 WRITE a:call_status
q:xprt_resend
Nov 23 09:12:27 frank kernel: [ 3855.702933] 15920 0001 -11 ffff88002f5dd200
ffff8800255953b0 0 ffffffffa035c500 nfsv4 WRITE a:call_status
q:xprt_resend
Nov 23 09:12:27 frank kernel: [ 3855.702970] 15922 0001 -11 ffff88002f5dd200
ffff880025594d20 0 ffffffffa035c500 nfsv4 WRITE a:call_status
q:xprt_resend
...
and so on as in the first comment.
Prior to lines 6587 as above, there appears to have been some corruption:
Nov 23 09:11:05 frank kernel: <48002000 mffffnnecting xprt f8.2 ] RPCabling
ti0 "xpr832.9.905609]connect_status (statusepare_transmit
Nov 23 09:11:05 frank kernel: [ 3832.905633] RPC: 16303PC: 16303 marshaling
UNIX cred ffff88002c710a40
Nov 23 09:11:05 frank kernel: [ 3832.905646] RPC: 16303 using AUTH_UNIX cred
ffff88002c710a40 to wrap rpc data
Nov 23 09:11:05 frank kernel: [ 3832.905654] RPC: 16303 xprt_transmit(131252)
Nov 23 09:11:05 frank kernel: [ 3832.905661] RPC:
xs_tcp_send_request(131252) = -32
Nov 23 09:11:05 frank kernel: [ 3832.905667] RPC: xs_tcp_state_change
client ffff88002c45c000...
Nov 23 09:11:05 frank kernel: [ 3832.905674] RPC: e 7 nectC: 16 RPC:
162.90505715] RPC: e 4295850522)
Nov 23 09:11:05 frank kernel: 8002000 fffnnecting xprt ffff8808.2. ] Rabling0
"x832..905790] RPC: connect_status (separe_transmit
Nov 23 09:11:05 frank kernel: [ 3832.905816] RPC: 16303 rpc_xdrPC: 16303
RP0a40 32.905843]32
Nov 23 09:11:06 frank kernel: [liene 7 nectC: RPC 1632.90505896] RPC: 16e
4295850522)
Nov 23 09:11:06 frank kernel: <8002c4000 mfffnnecting xprt ffff88.2 ]
RPC:abling timer
Nov 23 09:11:06 frank kernel: <0 "xpr832..905971] connect_status
(stepare_transmit
Nov 23 09:11:06 frank kernel: [ 3832.905997] RPC: 16303 rpPC: 16303
RP0a4032.906025] RP32
Nov 23 09:11:06 frank kernel: [ 3liene 7 necC: RPC 162.90606077] RPC: 16e
4295850522)8000 ffff8nnecting xprt 8.2 ] RPCabling tim0
"xp832..906154connect_status (staepare_transmit
Nov 23 09:11:06 frank kernel: [ 3832.906179] RPC: 16303 rpc_PC: 1630
RP0a4032.906207] 32
Nov 23 09:11:06 frank kernel: [ 383liee 7 necC: 1 RPC 1632.9006260] RPC: 1e
4295850522)8002c4000ffffnnecting xprt f8.2 ] RPabling 0
"xp832.9.90633connect_status epare_transmit
Nov 23 09:11:06 frank kernel: [ 3832.906362] RPC: 16303 rpcPC: 1630
RPC0a4032.906389] 32
Nov 23 09:11:06 frank kernel: [ 38liee 7 necC: RPC 1632.9006442] RPC: 1e
4295850522)8002c4000 mffffnnecting xprt ff8.2 ] RPCabling 0
"x832.9.9065connect_status (epare_transmit
Nov 23 09:11:06 frank kernel: [ 3832.906542] RPC: 16303PC: 1630 RPC0a40
32.906569] R32
Nov 23 09:11:06 frank kernel: [ 38liee 7 necteC: RPC: 162.9006623] RPCe
4295850522)
Nov 23 09:11:06 frank kernel: 8002c45c2f0 "xprt_pendin000 mffffnnecting xprt
f8.2 ] RPC:abling ti0 "x832..906699]connect_status (separe_transmit
I can still attach the whole thing.
Oh, look. More NFS weirdness:
[jnelson@worklaptop:~] ls -lah /multimedia/trace
-rw-r--r-- 1 4294967294 4294967294 997K 2009-11-30 20:43 /multimedia/trace
[jnelson@worklaptop:~]
sigh.
--
Configure bugmail: http://bugzilla.novell.com/userprefs.cgi?tab=email
------- You are receiving this mail because: -------
You are on the CC list for the bug.