Heh. Yep. I was thinking "I'll get those bastards!" ;-)
Yes I generated a lot of output to the client. Here are the relevant parts:
[D 14:20:13.331062] SM next smcb 0x8e6dd70 op 400
[D 14:20:13.331092] SM invoke smcb 0x8e6dd70 op 400
[D 14:20:13.331110] [SM Entering]: (0x8e6dd70) sysdev_unexp_sm:cleanup
(status: 0)
[D 14:20:13.331131] [SM Exiting]: (0x8e6dd70) sysdev_unexp_sm:cleanup
(error code: 0) [D 14:20:13.331238] SM Terminates (0x8e6dd70)
[D 14:20:13.331251] client_state_machine_terminate smcb 0x8e6dd70
[D 14:20:13.331264] add smcb 0x8e6dd70 to completion list
[D 14:20:13.331277] FRAME smcb 0x8e6dd70 base 0 stack 1 [D
14:20:13.331290] PVFS_sys_release id 4
[D 14:20:13.331303] FRAME smcb 0x8e6dd70 base 0 stack 1
[D 14:20:13.331316] SM free smcb 0x8e6dd70 op 400
[D 14:20:13.331331] PINT_sys_testsome returned unexp vfs_request
0x8e6c978 [D 14:20:13.331344] [+] dev req msg: sz: 668,tag: 100,data:
0x8eddb88,type: 0
[D 14:20:13.331358] [*] handling new unexp vfs_request 0x8e6c978
[D 14:20:13.331369] is_op_in_progress called on tag 100
[D 14:20:13.331397] Got an fs mount request for host:
tcp://sidious:3334/pvfs2-fs
[D 14:20:13.331478] Using Mount Point <DYNAMIC-1>
[D 14:20:13.331499] Got Configuration Server: tcp://sidious:3334
(len=18) [D 14:20:13.331513] Got FS Name: pvfs2-fs (len=8)
[D 14:20:13.331623] SM allocate smcb 0x8e6e028 op 19
[D 14:20:13.331694] PUSH smcb 0x8e6e028 base 0 stack 0
[D 14:20:13.331708] SM locate smcb 0x8e6e028 op 19 [D 14:20:13.331720]
client_op_state_get_machine 19
[D 14:20:13.331731] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.331743] FRAME smcb 0x8e6e028 base 0 stack 1 [D
14:20:13.331757] PINT_client_state_machine_post smcb 0x8e6e028, op:
PVFS_SYS_FS_ADD
[D 14:20:13.331770] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.331782] [SM Entering]: (0x8e6e028)
server_get_config_nested_sm:setup_msgpair (status: 0)
[D 14:20:13.331795] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.331806] get_config state: server_get_config_setup_msgpair
[D 14:20:13.331819] [SM Exiting]: (0x8e6e028)
server_get_config_nested_sm:setup_msgpair (error code: 0)
[D 14:20:13.331859] SM Returns Complete (0x8e6e028)
[D 14:20:13.331872] SM next smcb 0x8e6e028 op 19
[D 14:20:13.331884] SM invoke smcb 0x8e6e028 op 19 [D 14:20:13.331895]
[SM Entering]: (0x8e6e028) msgpairarray_sm:init (status: 0)
[D 14:20:13.331911] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.331895] [SM Entering]: (0x8e6e028) msgpairarray_sm:init
(status: 0)
[D 14:20:13.331911] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.331925] [SM Exiting]: (0x8e6e028) msgpairarray_sm:init
(error code: 0)
[D 14:20:13.331937] SM Returns Complete (0x8e6e028)
[D 14:20:13.331949] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.331960] [SM Entering]: (0x8e6e028) msgpairarray_sm:post
(status: 0)
[D 14:20:13.331972] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.332740] [SM Exiting]: (0x8e6e028) msgpairarray_sm:post
(error code: 0)
[D 14:20:13.332776] SM Returns Deferred (0x8e6e028)
[D 14:20:13.332797] Posted PVFS_SYS_FS_ADD (141) (waiting for test)(0)
[D 14:20:13.332867] SM next smcb 0x8e6e028 op 19
[D 14:20:13.332886] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.332904] [SM Entering]: (0x8e6e028) msgpairarray_sm:complete
(status: 1)
[D 14:20:13.332930] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.332954] [SM Exiting]: (0x8e6e028) msgpairarray_sm:complete
(error code: 0)
[D 14:20:13.333920] SM Returns Deferred (0x8e6e028)
[D 14:20:13.333983] SM next smcb 0x8e6e028 op 19
[D 14:20:13.334001] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.334017] [SM Entering]: (0x8e6e028) msgpairarray_sm:complete
(status: 0)
[D 14:20:13.334034] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.334051] [SM Exiting]: (0x8e6e028) msgpairarray_sm:complete
(error code: 190)
[D 14:20:13.334067] SM Returns Complete (0x8e6e028)
[D 14:20:13.334083] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.334098] [SM Entering]: (0x8e6e028)
msgpairarray_sm:completion_fn (status: 0)
[D 14:20:13.334122] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.334141] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.334164] [SM Exiting]: (0x8e6e028)
msgpairarray_sm:completion_fn (error code: 0)
[D 14:20:13.334182] SM Returns Complete (0x8e6e028)
[D 14:20:13.334198] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.334213] [SM Entering]: (0x8e6e028)
server_get_config_nested_sm:parse (status: 0)
[D 14:20:13.334230] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.335715] [SM Exiting]: (0x8e6e028)
server_get_config_nested_sm:parse (error code: 0)
[D 14:20:13.335742] SM Returns Complete (0x8e6e028)
[D 14:20:13.335759] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.335774] [SM Entering]: (0x8e6e028)
server_get_config_nested_sm:cleanup (status: 0)
[D 14:20:13.335790] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.335808] [SM Exiting]: (0x8e6e028)
server_get_config_nested_sm:cleanup (error code: 0)
[D 14:20:13.335827] SM Returns Complete (0x8e6e028)
[D 14:20:13.335845] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.335861] [SM Entering]: (0x8e6e028) fs_add_sm:parent_cleanup
(status: 0)
[D 14:20:13.335886] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.335926] * Adding new dynamic mount point <DYNAMIC-1> [7,0]
[D 14:20:13.335947] PINT_server_config_mgr_add_config: adding config
0x8e6e0d0
[D 14:20:13.335972] mapped fs_id 1437348217 => config 0x8e6e0d0
[D 14:20:13.336001] Set min handle recycle time to 360 seconds
[D 14:20:13.336019] Reloading handle mappings for fs_id 1437348217
[D 14:20:13.336041] [SM Exiting]: (0x8e6e028) fs_add_sm:parent_cleanup
(error code: 1)
[D 14:20:13.336167] SM Returns Complete (0x8e6e028)
[D 14:20:13.336193] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.336167] SM Returns Complete (0x8e6e028)
[D 14:20:13.336193] SM invoke smcb 0x8e6e028 op 19
[D 14:20:13.336211] [SM Entering]: (0x8e6e028) fs_add_sm:final_cleanup
(status: 0)
[D 14:20:13.336229] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.336246] [SM Exiting]: (0x8e6e028) fs_add_sm:final_cleanup
(error code: 0)
[D 14:20:13.336263] SM Terminates (0x8e6e028)
[D 14:20:13.336279] client_state_machine_terminate smcb 0x8e6e028 [D
14:20:13.336295] add smcb 0x8e6e028 to completion list
[D 14:20:13.336313] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.336331] PVFS_sys_release id 141
[D 14:20:13.336349] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.336368] SM free smcb 0x8e6e028 op 19
[D 14:20:13.336393] PINT_sys_testsome returned completed vfs_request
0x8e6c978 [D 14:20:13.336440] BMI forceful cancel mode enabled
[D 14:20:13.336466] FS mount got root handle 1048576 on fs id 1437348217
[D 14:20:13.365373] downcall write returned 1 [D 14:20:13.365418]
PVFS_sys_release id 141
[D 14:20:13.365441] PINT_sys_dev_unexp
[D 14:20:13.365458] SM allocate smcb 0x8e6e028 op 400
[D 14:20:13.365478] PUSH smcb 0x8e6e028 base 0 stack 0
[D 14:20:13.365495] SM locate smcb 0x8e6e028 op 400 [D 14:20:13.365510]
client_op_state_get_machine 400
[D 14:20:13.365526] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.365541] FRAME smcb 0x8e6e028 base 0 stack 1 [D
14:20:13.365557] PINT_client_state_machine_post smcb 0x8e6e028, op:
PVFS_DEV_UNEXPECTED
[D 14:20:13.365575] SM invoke smcb 0x8e6e028 op 400
[D 14:20:13.365591] [SM Entering]: (0x8e6e028) sysdev_unexp_sm:post
(status: 0) [D 14:20:13.365607] FRAME smcb 0x8e6e028 base 0 stack 1
[D 14:20:13.365625] [SM Exiting]: (0x8e6e028) sysdev_unexp_sm:post
(error code: 0)
[D 14:20:13.365643] SM Returns Deferred (0x8e6e028) [D 14:20:13.365661]
Posted PVFS_DEV_UNEXPECTED (146) (waiting for test)(0)
[D 14:20:13.365678] [-] reposted unexp req [0x8e6c978] due to
normal_completion
There is more, but after this point what you see is the unmount
requested by the kmod after it decides the mount failed (pvfs2_kill_sb).
All of this looks OK to me - the only things I noticed at all were that
fs_add_sm: parent_cleanup returned 1 and the downcall write returned 1.
I can't determine if either of those indicate an error.
Walt
Sam Lang wrote:
On Nov 9, 2006, at 1:51 PM, Walter B. Ligon III wrote:
follow up to this email (which I seem to have sent to myself):
Heh. Did you wonder why you weren't getting a response?
Walter B. Ligon III wrote:
I'm kind of stuck on the branch. ALL of the lib tests seem to work,
but when I try to mount via the kmod, mount returns "mount: Not a
directory"
As far as I can tell from the logs everything completes fine in the
client core, but the kernel mod or the mount program itself decides
there is a failure and requests an unmount. Not sure where to look
for this. Maybe someone who know about the mounting process can help?
Walt
I got the following log from the kmod setting the mask to "super"
I don't really know how to interpret this.
Nov 9 14:18:37 sidious kernel: pvfs2: module version
2.6.0pre1-2006-10-31-202141 loaded
Nov 9 14:20:13 sidious kernel: pvfs2: parse_mount_options called with:
Nov 9 14:20:13 sidious kernel: tcp://sidious:3334/pvfs2-fs,rw
Nov 9 14:20:13 sidious kernel: pvfs2: multiple device names specified:
ignoring rw
Nov 9 14:20:13 sidious kernel: Attempting PVFS2 Mount via host
tcp://sidious:3334/pvfs2-fs
Nov 9 14:20:13 sidious kernel: pvfs2_get_sb: mount got return value
of 0
Nov 9 14:20:13 sidious kernel: pvfs2-utils.c line 471: Critical error:
Invalid handle despite using iget4/iget5
Nov 9 14:20:13 sidious kernel: Allocated root inode [eedc8280] with
mode 100000Nov 9 14:20:13 sidious kernel: Adding SB e01b1a00 to pvfs2
superblocks
Nov 9 14:20:13 sidious kernel: pvfs2_put_inode: pvfs2_inode: efc9ff9c
(inode = 0) = 1 (nlink=2)
Nov 9 14:20:13 sidious kernel: pvfs2_clear_inode: deallocated efc9ff9c,
destroying inode 0
Nov 9 14:20:13 sidious kernel: pvfs2_kill_sb: called
Nov 9 14:20:13 sidious kernel: Removing SB e01b1a00 from pvfs2
superblocks
Nov 9 14:20:13 sidious kernel: pvfs2_kill_sb: (OK) number of inode
allocs (0) = number of inode deallocs (0)
Nov 9 14:20:13 sidious kernel: pvfs2_kill_sb: returning normally
Nov 9 14:20:38 sidious kernel: pvfs2: module version
2.6.0pre1-2006-10-31-202141 unloaded
This is with a 2.4 kernel right? What about with HEAD? Are you able
to mount using a build from HEAD? Also, is there anything in the
client log /tmp/pvfs2-client.log?
-sam
Walt
--
Dr. Walter B. Ligon III
Associate Professor
ECE Department
Clemson University
_______________________________________________
Pvfs2-developers mailing list
[email protected]
http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
--
Dr. Walter B. Ligon III
Associate Professor
ECE Department
Clemson University
_______________________________________________
Pvfs2-developers mailing list
[email protected]
http://www.beowulf-underground.org/mailman/listinfo/pvfs2-developers
