Bug 763006 (GLUSTER-1274)
Summary: | nfs fails to start | ||
---|---|---|---|
Product: | [Community] GlusterFS | Reporter: | Lakshmipathi G <lakshmipathi> |
Component: | nfs | Assignee: | Shehjar Tikoo <shehjart> |
Status: | CLOSED CURRENTRELEASE | QA Contact: | |
Severity: | high | Docs Contact: | |
Priority: | low | ||
Version: | 3.1-alpha | CC: | gluster-bugs |
Target Milestone: | --- | ||
Target Release: | --- | ||
Hardware: | All | ||
OS: | Linux | ||
Whiteboard: | |||
Fixed In Version: | Doc Type: | Bug Fix | |
Doc Text: | Story Points: | --- | |
Clone Of: | Environment: | ||
Last Closed: | Type: | --- | |
Regression: | RTP | Mount Type: | nfs |
Documentation: | --- | CRM: | |
Verified Versions: | Category: | --- | |
oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |
Cloudforms Team: | --- | Target Upstream Version: | |
Embargoed: |
Description
Lakshmipathi G
2010-08-03 14:05:34 UTC
Setting to blocker because NFS fails to start-up in a really simple configuration. Checking with simple posix+iot config. ,got the same error. Here is the log: -------- [2010-08-04 01:38:32.998097] D [glusterfsd.c:297:get_volfp] glusterfsd: loading volume file /opt/users/laks/nfs/854/854.vol [2010-08-04 01:38:33.2091] T [graph.y:192:new_volume] parser: New node for 'posix1' [2010-08-04 01:38:33.2113] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/storage/posix.so [2010-08-04 01:38:33.71973] T [graph.y:223:volume_type] parser: Type:posix1:storage/posix [2010-08-04 01:38:33.72003] T [graph.y:258:volume_option] parser: Option:posix1:directory:/mnt/laks [2010-08-04 01:38:33.72019] T [graph.y:365:volume_end] parser: end:posix1 [2010-08-04 01:38:33.72036] T [graph.y:192:new_volume] parser: New node for 'brick1' [2010-08-04 01:38:33.72052] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so [2010-08-04 01:38:33.115912] D [xlator.c:733:xlator_set_type] xlator: dlsym(notify) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: notify -- neglecting [2010-08-04 01:38:33.115935] D [xlator.c:738:xlator_set_type] xlator: dlsym(dumpops) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: dumpops -- neglecting [2010-08-04 01:38:33.115953] D [xlator.c:744:xlator_set_type] brick1: dlsym(mem_acct_init) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: mem_acct_init -- neglecting [2010-08-04 01:38:33.115972] D [xlator.c:758:xlator_set_type] brick1: Strict option validation not enforced -- neglecting [2010-08-04 01:38:33.115986] T [graph.y:223:volume_type] parser: Type:brick1:features/access-control [2010-08-04 01:38:33.116002] T [graph.y:348:volume_sub] parser: child:brick1->posix1 [2010-08-04 01:38:33.116016] T [graph.y:365:volume_end] parser: end:brick1 [2010-08-04 01:38:33.116032] T [graph.y:192:new_volume] parser: New node for 'nfsxlator' [2010-08-04 01:38:33.116048] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/nfs/server.so [2010-08-04 01:38:33.243137] D [xlator.c:738:xlator_set_type] xlator: dlsym(dumpops) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/nfs/server.so: undefined symbol: dumpops -- neglecting [2010-08-04 01:38:33.243177] T [graph.y:223:volume_type] parser: Type:nfsxlator:nfs/server [2010-08-04 01:38:33.243195] T [graph.y:348:volume_sub] parser: child:nfsxlator->brick1 [2010-08-04 01:38:33.243212] T [graph.y:258:volume_option] parser: Option:nfsxlator:rpc-auth.addr.allow:* [2010-08-04 01:38:33.243228] T [graph.y:365:volume_end] parser: end:nfsxlator [2010-08-04 01:38:33.243329] D [xlator.c:953:xlator_mem_acct_init] nfsxlator: Allocated mem_acct_rec for 86 types [2010-08-04 01:38:33.243372] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_GLUSTERFS [2010-08-04 01:38:33.243388] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_UNIX [2010-08-04 01:38:33.243402] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_NULL [2010-08-04 01:38:33.243416] D [rpcsvc.c:2061:rpcsvc_init] rpc-service: RPC service inited. [2010-08-04 01:38:33.243443] W [rpc-transport.c:878:rpc_transport_load] rpc-transport: missing 'option transport-type'. defaulting to "socket" [2010-08-04 01:38:33.243464] D [rpc-transport.c:930:rpc_transport_load] rpc-transport: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/rpc-transport/socket.so [2010-08-04 01:38:33.278407] D [dict.c:324:dict_get] dict: @this=(nil) @key=0x2aaaacb9e334 [2010-08-04 01:38:33.278429] D [dict.c:324:dict_get] dict: @this=(nil) @key=0x2aaaacb9e35e [2010-08-04 01:38:33.278450] D [name.c:553:server_fill_address_family] RPC: option address-family not specified, defaulting to inet/inet6 [2010-08-04 01:38:33.278604] T [socket.c:341:__socket_nodelay] : NODELAY enabled for socket 6 [2010-08-04 01:38:33.278659] T [rpcsvc.c:512:rpcsvc_conn_alloc] rpc-service: rx pool: 525 [2010-08-04 01:38:33.279307] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: GF-DUMP, Num: 123451501, Ver: 1, Port: 0 [2010-08-04 01:38:33.279440] T [nfs.c:344:nfs_init_subvolumes] nfs: inode table lru: 90000 [2010-08-04 01:38:33.279458] D [nfs.c:348:nfs_init_subvolumes] nfs: Initing subvolume: brick1 [2010-08-04 01:38:33.279936] T [nfs.c:368:nfs_init_subvolumes] nfs: Inited volumes: 1 [2010-08-04 01:38:33.279951] D [nfs.c:519:init] nfs: NFS service started [2010-08-04 01:38:33.279973] D [xlator.c:953:xlator_mem_acct_init] brick1: Allocated mem_acct_rec for 65 types [2010-08-04 01:38:33.280032] D [xlator.c:953:xlator_mem_acct_init] posix1: Allocated mem_acct_rec for 74 types [2010-08-04 01:38:33.280136] W [graph.c:120:_log_if_option_is_invalid] nfsxlator: option 'transport-type' is not recognized [2010-08-04 01:38:33.280154] W [graph.c:120:_log_if_option_is_invalid] nfsxlator: option 'rpc-auth.auth-glusterfs' is not recognized [2010-08-04 01:38:33.280175] T [nfs.c:535:notify] nfs: Notification received: 1 [2010-08-04 01:38:33.280193] T [nfs.c:535:notify] nfs: Notification received: 5 [2010-08-04 01:38:33.280207] D [nfs.c:211:nfs_subvolume_set_started] nfs: Starting up: brick1 , vols started till now: 1 [2010-08-04 01:38:33.280231] T [nfs-fops.c:279:nfs_fop_lookup] nfs: Lookup: / [2010-08-04 01:38:33.280281] T [nfs.c:235:nfs_start_subvol_lookup_cbk] nfs: Started brick1 [2010-08-04 01:38:33.280301] T [nfs.c:545:notify] nfs: All children up, starting RPC [2010-08-04 01:38:33.280315] D [nfs.c:107:nfs_init_versions] nfs: Initing protocol versions [2010-08-04 01:38:33.280338] D [mount3.c:1477:mnt3svc_init] nfs-mount: Initing Mount v3 state [2010-08-04 01:38:33.280359] T [mount3.c:1376:__mnt3_init_volume_export] nfs-mount: Volume exports enabled [2010-08-04 01:38:33.280373] T [mount3.c:1397:mnt3_init_options] nfs-mount: Initing options for: brick1 [2010-08-04 01:38:33.280388] T [mount3.c:1238:mnt3_init_export_ent] nfs-mount: Initing volume export: brick1 [2010-08-04 01:38:33.280403] D [nfs.c:123:nfs_init_versions] nfs: Starting program: MOUNT3 [2010-08-04 01:38:33.280479] T [posix.c:1273:posix_janitor_thread_proc] posix1: janitor cleaning out /.landfill [2010-08-04 01:38:33.280528] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: MOUNT3, Num: 100005, Ver: 3, Port: 0 [2010-08-04 01:38:33.280544] D [mount3.c:1521:mnt1svc_init] nfs-mount: Initing Mount v1 state [2010-08-04 01:38:33.280559] T [mount3.c:1376:__mnt3_init_volume_export] nfs-mount: Volume exports enabled [2010-08-04 01:38:33.280573] T [mount3.c:1397:mnt3_init_options] nfs-mount: Initing options for: brick1 [2010-08-04 01:38:33.280588] T [mount3.c:1238:mnt3_init_export_ent] nfs-mount: Initing volume export: brick1 [2010-08-04 01:38:33.280602] D [nfs.c:123:nfs_init_versions] nfs: Starting program: MOUNT1 [2010-08-04 01:38:33.280696] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: MOUNT1, Num: 100005, Ver: 1, Port: 0 [2010-08-04 01:38:33.280718] T [nfs3.c:4959:nfs3_init_state] nfs-nfsv3: local pool: 225 [2010-08-04 01:38:33.280929] T [nfs3.c:4879:nfs3_init_subvolume] nfs-nfsv3: Initing state: brick1 [2010-08-04 01:38:33.280946] T [nfs3.c:4859:nfs3_init_subvolume_options] nfs-nfsv3: brick1: read-write, no trusted_sync, no trusted_write [2010-08-04 01:38:33.280961] D [nfs.c:123:nfs_init_versions] nfs: Starting program: NFS3 [2010-08-04 01:38:33.281054] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: NFS3, Num: 100003, Ver: 3, Port: 0 [2010-08-04 01:39:17.768048] D [glusterfsd.c:1052:glusterfs_sigwaiter] sigwaiter: received signal 15 [2010-08-04 01:39:17.768137] N [glusterfsd.c:590:cleanup_and_exit] glusterfsd: shutting down [2010-08-04 01:39:25.937957] D [glusterfsd.c:297:get_volfp] glusterfsd: loading volume file /opt/users/laks/nfs/854/854.vol [2010-08-04 01:39:25.941793] T [graph.y:192:new_volume] parser: New node for 'posix1' [2010-08-04 01:39:25.941819] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/storage/posix.so [2010-08-04 01:39:26.1436] T [graph.y:223:volume_type] parser: Type:posix1:storage/posix [2010-08-04 01:39:26.1465] T [graph.y:258:volume_option] parser: Option:posix1:directory:/mnt/laks [2010-08-04 01:39:26.1481] T [graph.y:365:volume_end] parser: end:posix1 [2010-08-04 01:39:26.1499] T [graph.y:192:new_volume] parser: New node for 'brick1' [2010-08-04 01:39:26.1515] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so [2010-08-04 01:39:26.35011] D [xlator.c:733:xlator_set_type] xlator: dlsym(notify) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: notify -- neglecting [2010-08-04 01:39:26.35035] D [xlator.c:738:xlator_set_type] xlator: dlsym(dumpops) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: dumpops -- neglecting [2010-08-04 01:39:26.35054] D [xlator.c:744:xlator_set_type] brick1: dlsym(mem_acct_init) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/features/access-control.so: undefined symbol: mem_acct_init -- neglecting [2010-08-04 01:39:26.35073] D [xlator.c:758:xlator_set_type] brick1: Strict option validation not enforced -- neglecting [2010-08-04 01:39:26.35088] T [graph.y:223:volume_type] parser: Type:brick1:features/access-control [2010-08-04 01:39:26.35103] T [graph.y:348:volume_sub] parser: child:brick1->posix1 [2010-08-04 01:39:26.35118] T [graph.y:365:volume_end] parser: end:brick1 [2010-08-04 01:39:26.35134] T [graph.y:192:new_volume] parser: New node for 'nfsxlator' [2010-08-04 01:39:26.35150] T [xlator.c:698:xlator_set_type] xlator: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/nfs/server.so [2010-08-04 01:39:26.94406] D [xlator.c:738:xlator_set_type] xlator: dlsym(dumpops) on /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/xlator/nfs/server.so: undefined symbol: dumpops -- neglecting [2010-08-04 01:39:26.94430] T [graph.y:223:volume_type] parser: Type:nfsxlator:nfs/server [2010-08-04 01:39:26.94447] T [graph.y:348:volume_sub] parser: child:nfsxlator->brick1 [2010-08-04 01:39:26.94464] T [graph.y:258:volume_option] parser: Option:nfsxlator:rpc-auth.addr.allow:* [2010-08-04 01:39:26.94480] T [graph.y:365:volume_end] parser: end:nfsxlator [2010-08-04 01:39:26.94572] D [xlator.c:953:xlator_mem_acct_init] nfsxlator: Allocated mem_acct_rec for 86 types [2010-08-04 01:39:26.94614] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_GLUSTERFS [2010-08-04 01:39:26.94630] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_UNIX [2010-08-04 01:39:26.94644] T [rpcsvc-auth.c:117:rpcsvc_auth_init_auth] rpc-service: Authentication enabled: AUTH_NULL [2010-08-04 01:39:26.94657] D [rpcsvc.c:2061:rpcsvc_init] rpc-service: RPC service inited. [2010-08-04 01:39:26.94685] W [rpc-transport.c:878:rpc_transport_load] rpc-transport: missing 'option transport-type'. defaulting to "socket" [2010-08-04 01:39:26.94705] D [rpc-transport.c:930:rpc_transport_load] rpc-transport: attempt to load file /opt/glusterfs/3.1.0qa3/lib/glusterfs/3.1.0qa3/rpc-transport/socket.so [2010-08-04 01:39:26.128812] D [dict.c:324:dict_get] dict: @this=(nil) @key=0x2aaaacb9e334 [2010-08-04 01:39:26.128832] D [dict.c:324:dict_get] dict: @this=(nil) @key=0x2aaaacb9e35e [2010-08-04 01:39:26.128853] D [name.c:553:server_fill_address_family] RPC: option address-family not specified, defaulting to inet/inet6 [2010-08-04 01:39:26.128959] T [socket.c:341:__socket_nodelay] : NODELAY enabled for socket 6 [2010-08-04 01:39:26.129001] T [rpcsvc.c:512:rpcsvc_conn_alloc] rpc-service: rx pool: 525 [2010-08-04 01:39:26.129603] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: GF-DUMP, Num: 123451501, Ver: 1, Port: 0 [2010-08-04 01:39:26.129736] T [nfs.c:344:nfs_init_subvolumes] nfs: inode table lru: 90000 [2010-08-04 01:39:26.129754] D [nfs.c:348:nfs_init_subvolumes] nfs: Initing subvolume: brick1 [2010-08-04 01:39:26.130234] T [nfs.c:368:nfs_init_subvolumes] nfs: Inited volumes: 1 [2010-08-04 01:39:26.130251] D [nfs.c:519:init] nfs: NFS service started [2010-08-04 01:39:26.130273] D [xlator.c:953:xlator_mem_acct_init] brick1: Allocated mem_acct_rec for 65 types [2010-08-04 01:39:26.130323] D [xlator.c:953:xlator_mem_acct_init] posix1: Allocated mem_acct_rec for 74 types [2010-08-04 01:39:26.130419] W [graph.c:120:_log_if_option_is_invalid] nfsxlator: option 'transport-type' is not recognized [2010-08-04 01:39:26.130436] W [graph.c:120:_log_if_option_is_invalid] nfsxlator: option 'rpc-auth.auth-glusterfs' is not recognized [2010-08-04 01:39:26.130457] T [nfs.c:535:notify] nfs: Notification received: 1 [2010-08-04 01:39:26.130474] T [nfs.c:535:notify] nfs: Notification received: 5 [2010-08-04 01:39:26.130488] D [nfs.c:211:nfs_subvolume_set_started] nfs: Starting up: brick1 , vols started till now: 1 [2010-08-04 01:39:26.130509] T [nfs-fops.c:279:nfs_fop_lookup] nfs: Lookup: / [2010-08-04 01:39:26.130550] T [nfs.c:235:nfs_start_subvol_lookup_cbk] nfs: Started brick1 [2010-08-04 01:39:26.130567] T [nfs.c:545:notify] nfs: All children up, starting RPC [2010-08-04 01:39:26.130580] D [nfs.c:107:nfs_init_versions] nfs: Initing protocol versions [2010-08-04 01:39:26.130596] D [mount3.c:1477:mnt3svc_init] nfs-mount: Initing Mount v3 state [2010-08-04 01:39:26.130616] T [mount3.c:1376:__mnt3_init_volume_export] nfs-mount: Volume exports enabled [2010-08-04 01:39:26.130630] T [mount3.c:1397:mnt3_init_options] nfs-mount: Initing options for: brick1 [2010-08-04 01:39:26.130645] T [mount3.c:1238:mnt3_init_export_ent] nfs-mount: Initing volume export: brick1 [2010-08-04 01:39:26.130660] D [nfs.c:123:nfs_init_versions] nfs: Starting program: MOUNT3 [2010-08-04 01:39:26.130746] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: MOUNT3, Num: 100005, Ver: 3, Port: 0 [2010-08-04 01:39:26.130764] D [mount3.c:1521:mnt1svc_init] nfs-mount: Initing Mount v1 state [2010-08-04 01:39:26.130779] T [mount3.c:1376:__mnt3_init_volume_export] nfs-mount: Volume exports enabled [2010-08-04 01:39:26.130792] T [mount3.c:1397:mnt3_init_options] nfs-mount: Initing options for: brick1 [2010-08-04 01:39:26.130808] T [mount3.c:1238:mnt3_init_export_ent] nfs-mount: Initing volume export: brick1 [2010-08-04 01:39:26.130822] D [nfs.c:123:nfs_init_versions] nfs: Starting program: MOUNT1 [2010-08-04 01:39:26.130905] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: MOUNT1, Num: 100005, Ver: 1, Port: 0 [2010-08-04 01:39:26.130926] T [nfs3.c:4959:nfs3_init_state] nfs-nfsv3: local pool: 225 [2010-08-04 01:39:26.131137] T [nfs3.c:4879:nfs3_init_subvolume] nfs-nfsv3: Initing state: brick1 [2010-08-04 01:39:26.131154] T [nfs3.c:4859:nfs3_init_subvolume_options] nfs-nfsv3: brick1: read-write, no trusted_sync, no trusted_write [2010-08-04 01:39:26.131168] D [nfs.c:123:nfs_init_versions] nfs: Starting program: NFS3 [2010-08-04 01:39:26.131251] D [rpcsvc.c:1917:rpcsvc_program_register] rpc-service: New program registered: NFS3, Num: 100003, Ver: 3, Port: 0 [2010-08-04 01:39:26.131311] T [posix.c:1273:posix_janitor_thread_proc] posix1: janitor cleaning out /.landfill ---------------------- Confirmed that calls to RPC lib in NFS is going into the rpc lib in rpc-transport. PATCH: http://patches.gluster.com/patch/4054 in master (nfs, nfs/rpc: Rename functions to prevent gfrpcsvc conflict) PATCH: http://patches.gluster.com/patch/4050 in master (nfs: Do not build nfs/rpc as shared library) |