Attachment 653211 Details for Bug 874372 – NFS debug information for 3.6.1-1.fc17.x86_64 after running a script as in comment #23

NFS debug information for 3.6.1-1.fc17.x86_64 after running a script as in comment #23

nfs_debug.3.6.1-1.fc17.x86_64 (text/plain), 13.32 KB, created by Michal Jaegermann on 2012-11-28 02:05:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Michal Jaegermann

Created: 2012-11-28 02:05:12 UTC

Size: 13.32 KB

patch

obsolete

>[   69.289556] RPC: Registered named UNIX socket transport module.
>[   69.289562] RPC: Registered udp transport module.
>[   69.289564] RPC: Registered tcp transport module.
>[   69.289566] RPC: Registered tcp NFSv4.1 backchannel transport module.
>[   69.614440] FS-Cache: Loaded
>[   69.627229] Key type dns_resolver registered
>[   69.659666] FS-Cache: Netfs 'nfs' registered for caching
>[   69.659672] RPC:       creating workqueue nfsiod
>[   69.660760] NFS: nfs mount opts='vers=4,addr=192.168.23.2,clientaddr=192.168.23.192'
>[   69.660772] NFS:   parsing nfs mount option 'vers=4'
>[   69.660779] NFS:   parsing nfs mount option 'addr=192.168.23.2'
>[   69.660785] NFS:   parsing nfs mount option 'clientaddr=192.168.23.192'
>[   69.660791] NFS: MNTPATH: '/home'
>[   69.704016] NFS: Registering the id_resolver key type
>[   69.704038] Key type id_resolver registered
>[   69.704040] Key type id_legacy registered
>[   69.704380] --> nfs4_try_mount()
>[   69.704390] --> nfs4_create_server()
>[   69.704412] --> nfs4_init_server()
>[   69.704414] --> nfs4_set_client()
>[   69.704417] --> nfs_get_client(zeno,v4)
>[   69.704424] NFS: get client cookie (0xffff880065e4c400/0xffff880065dc3050)
>[   69.705375] NFS: Callback listener port = 60142 (af 2)
>[   69.705397] NFS: Callback listener port = 60180 (af 10)
>[   69.705452] <-- nfs4_set_client() = 0 [new ffff880065e4c400]
>[   69.705459] <-- nfs4_init_server() = 0
>[   69.705461] --> nfs4_get_rootfh()
>[   69.705824] nfs4_get_rootfh: getroot error = 2
>[   69.705826] <-- nfs4_get_rootfh() = -2
>[   69.705828] --> nfs_free_server()
>[   69.705832] --> nfs_put_client({1})
>[   69.705862] --> nfs_free_client(4)
>[   69.705864] NFS: releasing client cookie (0xffff880065e4c400/0xffff880065dc3050)
>[   69.705882] <-- nfs_free_client()
>[   69.705891] <-- nfs_free_server()
>[   69.705893] <-- nfs4_create_server() = error -2
>[   69.705898] <-- nfs4_try_mount() = -2 [error]
>[   69.713330] NFS: nfs mount opts='addr=192.168.23.2,vers=3,proto=tcp,mountvers=3,mountproto=udp,mountport=20048'
>[   69.713347] NFS:   parsing nfs mount option 'addr=192.168.23.2'
>[   69.713355] NFS:   parsing nfs mount option 'vers=3'
>[   69.713360] NFS:   parsing nfs mount option 'proto=tcp'
>[   69.713365] NFS:   parsing nfs mount option 'mountvers=3'
>[   69.713370] NFS:   parsing nfs mount option 'mountproto=udp'
>[   69.713374] NFS:   parsing nfs mount option 'mountport=20048'
>[   69.713380] NFS: MNTPATH: '/home'
>[   69.721898] NFS: sending MNT request for zeno:/home
>[   69.723643] NFS: received 1 auth flavors
>[   69.723646] NFS:   auth flavor[0]: 1
>[   69.723664] NFS: MNT request succeeded
>[   69.723666] NFS: using auth flavor 1
>[   69.723688] --> nfs_init_server()
>[   69.723691] --> nfs_get_client(zeno,v3)
>[   69.723697] NFS: get client cookie (0xffff880065e4d000/0xffff880065dc3050)
>[   69.726659] <-- nfs_init_server() = 0 [new ffff880065e4d000]
>[   69.726663] --> nfs_probe_fsinfo()
>[   69.726665] NFS call  fsinfo
>[   69.727126] NFS reply fsinfo: 0
>[   69.727130] NFS call  pathconf
>[   69.727528] NFS reply pathconf: 0
>[   69.727530] <-- nfs_probe_fsinfo() = 0
>[   69.727532] NFS call  getattr
>[   69.727917] NFS reply getattr: 0
>[   69.727920] Server FSID: b5cf34e31582e47a:0
>[   69.728472] NFS: get superblock cookie (0xffff880065e85000/0xffff880065dc30a0)
>[   69.728475] do_proc_get_root: call  fsinfo
>[   69.729334] do_proc_get_root: reply fsinfo: 0
>[   69.729694] do_proc_get_root: reply getattr: 0
>[   69.729709] NFS: nfs_fhget(0:32/2 fh_crc=0x18551419 ct=1)
>[   69.729849] SELinux: initialized (dev 0:32, type nfs), uses genfs_contexts
>[   71.746659] NFS: nfs_lookup_revalidate(/) is valid
>[   71.746670] NFS call  access
>[   71.747208] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   71.747212] NFS reply access: 0
>[   71.747215] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.747423] NFS: lookup(/spare)
>[   71.747426] NFS call  lookup spare
>[   71.747796] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   71.747798] NFS reply lookup: 0
>[   71.747806] NFS: nfs_fhget(0:32/1441793 fh_crc=0x2aaf290d ct=1)
>[   71.747812] NFS: dentry_delete(/spare, 8c)
>[   71.750055] NFS: nfs_lookup_revalidate(/) is valid
>[   71.750069] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.750072] NFS: nfs_lookup_revalidate(/spare) is valid
>[   71.750075] NFS: dentry_delete(/spare, cc)
>[   71.750086] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.750088] NFS: nfs_lookup_revalidate(/spare) is valid
>[   71.750092] NFS: dentry_delete(/spare, cc)
>[   71.750097] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.750099] NFS: nfs_lookup_revalidate(/spare) is valid
>[   71.750102] NFS call  access
>[   71.750626] NFS: nfs_update_inode(0:32/1441793 fh_crc=0x2aaf290d ct=1 info=0x27e7f)
>[   71.750630] NFS reply access: 0
>[   71.750633] NFS: permission(0:32/1441793), mask=0x11, res=0
>[   71.750635] NFS: dentry_delete(/spare, cc)
>[   71.751828] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.751832] NFS: nfs_lookup_revalidate(/spare) is valid
>[   71.751854] NFS: nfs mount opts='vers=4,addr=192.168.23.2,clientaddr=192.168.23.192'
>[   71.751865] NFS:   parsing nfs mount option 'vers=4'
>[   71.751872] NFS:   parsing nfs mount option 'addr=192.168.23.2'
>[   71.751878] NFS:   parsing nfs mount option 'clientaddr=192.168.23.192'
>[   71.751884] NFS: MNTPATH: '/home/spare'
>[   71.751886] --> nfs4_try_mount()
>[   71.751893] --> nfs4_create_server()
>[   71.751914] --> nfs4_init_server()
>[   71.751916] --> nfs4_set_client()
>[   71.751918] --> nfs_get_client(zeno,v4)
>[   71.751924] NFS: get client cookie (0xffff880065e4dc00/0xffff880065dc30f0)
>[   71.752812] NFS: Callback listener port = 50772 (af 2)
>[   71.752831] NFS: Callback listener port = 46556 (af 10)
>[   71.752891] <-- nfs4_set_client() = 0 [new ffff880065e4dc00]
>[   71.752899] <-- nfs4_init_server() = 0
>[   71.752901] --> nfs4_get_rootfh()
>[   71.753273] nfs4_get_rootfh: getroot error = 2
>[   71.753276] <-- nfs4_get_rootfh() = -2
>[   71.753278] --> nfs_free_server()
>[   71.753282] --> nfs_put_client({1})
>[   71.753315] --> nfs_free_client(4)
>[   71.753318] NFS: releasing client cookie (0xffff880065e4dc00/0xffff880065dc30f0)
>[   71.753335] <-- nfs_free_client()
>[   71.753345] <-- nfs_free_server()
>[   71.753347] <-- nfs4_create_server() = error -2
>[   71.753351] <-- nfs4_try_mount() = -2 [error]
>[   71.753356] NFS: dentry_delete(/spare, cc)
>[   71.758541] NFS: permission(0:32/2), mask=0x1, res=0
>[   71.758549] NFS: nfs_lookup_revalidate(/spare) is valid
>[   71.758572] NFS: nfs mount opts='addr=192.168.23.2,vers=3,proto=tcp,mountvers=3,mountproto=udp,mountport=20048'
>[   71.758584] NFS:   parsing nfs mount option 'addr=192.168.23.2'
>[   71.758591] NFS:   parsing nfs mount option 'vers=3'
>[   71.758596] NFS:   parsing nfs mount option 'proto=tcp'
>[   71.758600] NFS:   parsing nfs mount option 'mountvers=3'
>[   71.758605] NFS:   parsing nfs mount option 'mountproto=udp'
>[   71.758610] NFS:   parsing nfs mount option 'mountport=20048'
>[   71.758615] NFS: MNTPATH: '/home/spare'
>[   71.758619] NFS: sending MNT request for zeno:/home/spare
>[   71.760292] NFS: received 1 auth flavors
>[   71.760294] NFS:   auth flavor[0]: 1
>[   71.760308] NFS: MNT request succeeded
>[   71.760309] NFS: using auth flavor 1
>[   71.760331] --> nfs_init_server()
>[   71.760333] --> nfs_get_client(zeno,v3)
>[   71.760336] <-- nfs_found_client found nfs_client ffff880065e4d000 for zeno
>[   71.760345] <-- nfs_init_server() = 0 [new ffff880065e4d000]
>[   71.760346] --> nfs_probe_fsinfo()
>[   71.760348] NFS call  fsinfo
>[   71.760646] NFS reply fsinfo: 0
>[   71.760649] NFS call  pathconf
>[   71.760998] NFS reply pathconf: 0
>[   71.761000] <-- nfs_probe_fsinfo() = 0
>[   71.761011] NFS call  getattr
>[   71.761561] NFS reply getattr: 0
>[   71.761564] Server FSID: ee8dae646ff71a61:0
>[   71.762856] NFS: get superblock cookie (0xffff880065e6c000/0xffff880065dc30f0)
>[   71.762860] do_proc_get_root: call  fsinfo
>[   71.763305] do_proc_get_root: reply fsinfo: 0
>[   71.763505] do_proc_get_root: reply getattr: 0
>[   71.763514] NFS: nfs_fhget(0:33/2 fh_crc=0x99b6932b ct=1)
>[   71.763525] SELinux: initialized (dev 0:33, type nfs), uses genfs_contexts
>[   73.788910] NFS: permission(0:32/2), mask=0x1, res=0
>[   73.788919] NFS: nfs_lookup_revalidate(/spare) is valid
>[   73.788923] NFS: nfs_lookup_revalidate(/) is valid
>[   73.790928] NFS: permission(0:32/2), mask=0x1, res=0
>[   73.790932] NFS: nfs_lookup_revalidate(/spare) is valid
>[   73.790935] NFS: nfs_lookup_revalidate(/) is valid
>[   78.217212] NFS: nfs_lookup_revalidate(/) is valid
>[   79.132174] NFS: nfs_lookup_revalidate(/) is valid
>[   79.132183] NFS: revalidating (0:32/2)
>[   79.132185] NFS call  getattr
>[   79.132663] NFS reply getattr: 0
>[   79.132668] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   79.132672] NFS: (0:32/2) revalidation complete
>[   79.132674] NFS: nfs_lookup_revalidate(/) is valid
>[   79.132679] NFS: permission(0:32/2), mask=0x24, res=0
>[   79.132683] NFS: open dir(/)
>[   79.132684] NFS: revalidating (0:32/2)
>[   79.132686] NFS call  getattr
>[   79.133043] NFS reply getattr: 0
>[   79.133047] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   79.133049] NFS: (0:32/2) revalidation complete
>[   79.133056] NFS: readdir(/) starting at cookie 0
>[   79.133068] NFS call  readdirplus 0
>[   79.133759] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   79.133762] NFS reply readdirplus: 1160
>[   79.133775] NFS: nfs_fhget(0:32/2097153 fh_crc=0x7242e99f ct=1)
>[   79.133781] NFS: dentry_delete(/michal, 8c)
>[   79.133788] NFS: nfs_fhget(0:32/12 fh_crc=0x67a31cdf ct=1)
>[   79.133790] NFS: dentry_delete(/home, 8c)
>[   79.133796] NFS: nfs_fhget(0:32/1048577 fh_crc=0x47cc6cf0 ct=1)
>[   79.133798] NFS: dentry_delete(/nicole, 8c)
>[   79.133803] NFS: nfs_fhget(0:32/11 fh_crc=0x617584eb ct=1)
>[   79.133805] NFS: dentry_delete(/lost+found, 8c)
>[   79.133810] NFS: nfs_fhget(0:32/1572865 fh_crc=0x6a4094fe ct=1)
>[   79.133812] NFS: dentry_delete(/tmp.var, 8c)
>[   79.133816] nfs_fhget: iget failed with error -2
>[   79.133818] NFS: dentry_delete(/spare, c)
>[   79.133833] NFS: nfs_fhget(0:32/1703937 fh_crc=0x2f44a5e4 ct=1)
>[   79.133835] NFS: dentry_delete(/eryk, 8c)
>[   79.133844] NFS: nfs_do_filldir() filling ended @ cookie 9223372036854775807; returning = 0
>[   79.133847] NFS: readdir(/) returns 0
>[   79.133854] NFS: readdir(/) starting at cookie 9
>[   79.133857] NFS: readdir(/) returns 0
>[   79.133875] NFS: permission(0:32/2), mask=0x1, res=0
>[   79.133878] NFS: lookup(/spare)
>[   79.133881] NFS call  lookup spare
>[   79.134342] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   79.134345] NFS reply lookup: 0
>[   79.134349] NFS: nfs_update_inode(0:32/1441793 fh_crc=0x2aaf290d ct=2 info=0x27e7f)
>[   79.134351] NFS: nfs_fhget(0:32/1441793 fh_crc=0x2aaf290d ct=2)
>[   79.134354] NFS: dentry_delete(/spare, c)
>[   79.550435] NFS: permission(0:32/2), mask=0x1, res=0
>[   79.550444] NFS: lookup(/spare)
>[   79.550448] NFS call  lookup spare
>[   79.550997] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[   79.551015] NFS reply lookup: 0
>[   79.551158] NFS: nfs_update_inode(0:32/1441793 fh_crc=0x2aaf290d ct=2 info=0x27e7f)
>[   79.551162] NFS: nfs_fhget(0:32/1441793 fh_crc=0x2aaf290d ct=2)
>[   79.551167] NFS: dentry_delete(/spare, c)
>[  260.408196] NFS: nfs_lookup_revalidate(/) is valid
>[  260.408204] NFS: revalidating (0:32/2)
>[  260.408206] NFS call  getattr
>[  260.408695] NFS reply getattr: 0
>[  260.408701] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  260.408704] NFS: (0:32/2) revalidation complete
>[  261.020207] NFS: nfs_lookup_revalidate(/) is valid
>[  261.020215] NFS: revalidating (0:32/2)
>[  261.020217] NFS call  getattr
>[  261.020673] NFS reply getattr: 0
>[  261.020677] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  261.020680] NFS: (0:32/2) revalidation complete
>[  261.020682] NFS: nfs_lookup_revalidate(/) is valid
>[  261.020687] NFS call  access
>[  261.021134] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  261.021137] NFS reply access: 0
>[  261.021140] NFS: permission(0:32/2), mask=0x24, res=0
>[  261.021143] NFS: open dir(/)
>[  261.021145] NFS: revalidating (0:32/2)
>[  261.021147] NFS call  getattr
>[  261.021536] NFS reply getattr: 0
>[  261.021539] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  261.021542] NFS: (0:32/2) revalidation complete
>[  261.021549] NFS: readdir(/) starting at cookie 0
>[  261.021556] NFS: nfs_do_filldir() filling ended @ cookie 9223372036854775807; returning = 0
>[  261.021558] NFS: readdir(/) returns 0
>[  261.021564] NFS: readdir(/) starting at cookie 9
>[  261.021566] NFS: readdir(/) returns 0
>[  261.021582] NFS: permission(0:32/2), mask=0x1, res=0
>[  261.021587] NFS: lookup(/spare)
>[  261.021589] NFS call  lookup spare
>[  261.021968] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  261.021970] NFS reply lookup: 0
>[  261.021975] NFS: nfs_update_inode(0:32/1441793 fh_crc=0x2aaf290d ct=2 info=0x27e7f)
>[  261.021978] NFS: nfs_fhget(0:32/1441793 fh_crc=0x2aaf290d ct=2)
>[  261.021982] NFS: dentry_delete(/spare, c)
>[  261.318327] NFS: permission(0:32/2), mask=0x1, res=0
>[  261.318334] NFS: lookup(/spare)
>[  261.318339] NFS call  lookup spare
>[  261.318868] NFS: nfs_update_inode(0:32/2 fh_crc=0x18551419 ct=2 info=0x27e7f)
>[  261.318872] NFS reply lookup: 0
>[  261.318877] NFS: nfs_update_inode(0:32/1441793 fh_crc=0x2aaf290d ct=2 info=0x27e7f)
>[  261.318880] NFS: nfs_fhget(0:32/1441793 fh_crc=0x2aaf290d ct=2)
>[  261.318884] NFS: dentry_delete(/spare, c)

Actions: View

Attachments on bug 874372: 640528 | 640529 | 653209 | 653210 | 653211 | 653212 | 654703 | 654705