[Samba] VFS module core dump in make_connection

Jiri Klouda jk at zg.cz
Sat Jun 25 08:44:04 GMT 2005 

Hi,
I have a problem with a VFS module that I am developing. The module
has been runing fine under 3.0.6, but then I didn't have time to work
on it for a while and meanwhile I upgraded samba to the latest sarge
debian release 3.0.14a and now that I have got the sources and again
recompiled my module and included it into the /usr/lib/samba/vfs dir,
its now core dumping. I am sort of at loss here trying to debug this
problem so if anybody would have any hints, they are welcome.

Here are some excerpts from the logs:

[2005/06/25 01:32:33, 5] lib/util_seaccess.c:se_access_check(309)
  se_access_check: access (2) granted.
[2005/06/25 01:32:33, 3] smbd/vfs.c:vfs_init_default(206)
  Initialising default vfs hooks
[2005/06/25 01:32:33, 3] smbd/vfs.c:vfs_init_custom(232)
  Initialising custom vfs hooks from [dbvfs]
[2005/06/25 01:32:33, 5] lib/module.c:smb_probe_module(104)
  Probing module 'dbvfs'
[2005/06/25 01:32:33, 5] lib/module.c:smb_probe_module(115)
  Probing module 'dbvfs': Trying to load from /usr/lib/samba/vfs/dbvfs.so
[2005/06/25 01:32:33, 5] smbd/vfs.c:smb_register_vfs(196)
  Successfully added vfs backend 'dbvfs'
[2005/06/25 01:32:33, 2] lib/module.c:do_smb_load_module(66)
  Module '/usr/lib/samba/vfs/dbvfs.so' loaded
  Successfully loaded vfs module [dbvfs] with the new modules system
[2005/06/25 01:32:33, 5] smbd/vfs.c:vfs_init_custom(278)
  Checking operation #0 (type 0, layer 0)
  Making operation type 0 opaque [module dbvfs]
  Accepting operation type 0 from module dbvfs
[2005/06/25 01:32:33, 5] smbd/vfs.c:vfs_init_custom(278)
  Checking operation #1 (type 1, layer 0)
  Making operation type 1 opaque [module dbvfs]
  Accepting operation type 1 from module dbvfs
.....
.....
[2005/06/25 01:32:33, 5] smbd/vfs.c:vfs_init_custom(278)
  Checking operation #80 (type 80, layer 0)
  Making operation type 80 opaque [module dbvfs]
  Accepting operation type 80 from module dbvfs
[2005/06/25 01:32:33, 5] smbd/vfs.c:vfs_init_custom(278)
  Checking operation #81 (type 81, layer 0)
  Making operation type 81 opaque [module dbvfs]
  Accepting operation type 81 from module dbvfs
[2005/06/25 01:32:33, 5] smbd/connection.c:claim_connection(170)
  claiming db 0
[2005/06/25 01:32:33, 10] lib/username.c:user_in_list(529)
  user_in_list: checking user jk in list
[2005/06/25 01:32:33, 10] lib/username.c:user_in_list(533)
  user_in_list: checking user |jk| against |root|
[2005/06/25 01:32:33, 10] smbd/uid.c:is_share_read_only_for_user(99)
  is_share_read_only_for_user: share db is read-write for unix user jk
[2005/06/25 01:32:33, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for db
[2005/06/25 01:32:33, 10] lib/util_seaccess.c:se_map_generic(176)
  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2005/06/25 01:32:33, 10] lib/util_seaccess.c:se_access_check(234)
  se_access_check: requested access 0x00000002, for NT token with 21 entries and first sid S-1-5-21-2577336617-3256883420-1772676905-2002.
[2005/06/25 01:32:33, 3] lib/util_seaccess.c:se_access_check(251)
[2005/06/25 01:32:33, 3] lib/util_seaccess.c:se_access_check(252)
  se_access_check: user sid is S-1-5-21-2577336617-3256883420-1772676905-2002
  se_access_check: also S-1-5-21-2577336617-3256883420-1772676905-3021
  .....
  se_access_check: also S-1-5-21-2577336617-3256883420-1772676905-5009
  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2
[2005/06/25 01:32:33, 5] lib/util_seaccess.c:se_access_check(309)
  se_access_check: access (2) granted.
[2005/06/25 01:32:33, 4] rpc_server/srv_srvsvc_nt.c:get_share_security(217)
  get_share_security: using default secdesc for db
[2005/06/25 01:32:33, 10] lib/util_seaccess.c:se_map_generic(176)
  se_map_generic(): mapped mask 0x10000000 to 0x001f01ff
[2005/06/25 01:32:33, 10] lib/util_seaccess.c:se_access_check(234)
  se_access_check: requested access 0x00000002, for NT token with 21 entries and first sid S-1-5-21-2577336617-3256883420-1772676905-2002.
[2005/06/25 01:32:33, 3] lib/util_seaccess.c:se_access_check(251)
[2005/06/25 01:32:33, 3] lib/util_seaccess.c:se_access_check(252)
  se_access_check: user sid is S-1-5-21-2577336617-3256883420-1772676905-2002
  se_access_check: also S-1-5-21-2577336617-3256883420-1772676905-3021
  .....
  se_access_check: also S-1-5-21-2577336617-3256883420-1772676905-5009
  se_access_check: ACE 0: type 0, flags = 0x00, SID = S-1-1-0 mask = 101f01ff, current desired = 2
[2005/06/25 01:32:33, 5] lib/util_seaccess.c:se_access_check(309)
  se_access_check: access (2) granted.
[2005/06/25 01:32:33, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (501, 1010) - sec_ctx_stack_ndx = 0
[2005/06/25 01:32:33, 5] auth/auth_util.c:debug_nt_user_token(490)
  NT user token of user S-1-5-21-2577336617-3256883420-1772676905-2002
  contains 21 SIDs
  SID[  0]: S-1-5-21-2577336617-3256883420-1772676905-2002
  SID[  1]: S-1-5-21-2577336617-3256883420-1772676905-3021
  .....
  SID[ 20]: S-1-5-21-2577336617-3256883420-1772676905-5009
  SE_PRIV  0x0 0x0 0x0 0x0
[2005/06/25 01:32:33, 5] auth/auth_util.c:debug_unix_user_token(506)
  UNIX token of user 501
  Primary group is 1010 and contains 16 supplementary groups
  Group[  0]: 1010
  .....
  Group[ 15]: 2004
[2005/06/25 01:32:33, 5] smbd/uid.c:change_to_user(281)
  change_to_user uid=(501,501) gid=(0,1010)
[2005/06/25 01:32:33, 0] lib/fault.c:fault_report(36)
  ===============================================================
[2005/06/25 01:32:33, 0] lib/fault.c:fault_report(37)
  INTERNAL ERROR: Signal 11 in pid 30041 (3.0.14a-Debian)
  Please read the appendix Bugs of the Samba HOWTO collection
[2005/06/25 01:32:33, 0] lib/fault.c:fault_report(39)
  ===============================================================
[2005/06/25 01:32:33, 0] lib/util.c:smb_panic2(1485)
  smb_panic(): calling panic action [/usr/share/samba/panic-action 30041]
[2005/06/25 01:32:37, 0] lib/util.c:smb_panic2(1493)
  smb_panic(): action returned status 0
[2005/06/25 01:32:37, 0] lib/util.c:smb_panic2(1495)
  PANIC: internal error
[2005/06/25 01:32:37, 0] lib/util.c:smb_panic2(1503)
  BACKTRACE: 14 stack frames:
   #0 /usr/sbin/smbd(smb_panic2+0x111) [0x81eb301]
   #1 /usr/sbin/smbd(smb_panic+0x1a) [0x81eb1ea]
   #2 /usr/sbin/smbd [0x81d7368]
   #3 [0xffffe420]
   #4 /usr/sbin/smbd [0x80d9f3a]
   #5 /usr/sbin/smbd(make_connection+0x12f) [0x80dafaf]
   #6 /usr/sbin/smbd(reply_tcon_and_X+0x1ac) [0x80a211c]
   #7 /usr/sbin/smbd [0x80d7a76]
   #8 /usr/sbin/smbd [0x80d7d00]
   #9 /usr/sbin/smbd(process_smb+0x8c) [0x80d7f0c]
   #10 /usr/sbin/smbd(smbd_process+0x168) [0x80d8c58]
   #11 /usr/sbin/smbd(main+0x505) [0x82655f5]
   #12 /lib/tls/libc.so.6(__libc_start_main+0xf4) [0x401bb974]
   #13 /usr/sbin/smbd [0x8079b01]

Here is what happens on the command line with full logs when I try to
mount the volume:

smb_bcc=14
[000] D7 D9 8E 4B 5F D6 D0 B1  5A 00 47 00 00 00        ...K_... Z.G...
Serverzone is 25200
Password: 
write_socket(3,130)
write_socket(3,130) wrote 130
got smb length of 70
size=70
smb_com=0x73
smb_rcls=0
smb_reh=0
smb_err=0
smb_flg=136
smb_flg2=16385
smb_tid=0
smb_pid=30040
smb_uid=100
smb_mid=2
smt_wct=3
smb_vwv[ 0]=  255 (0xFF)
smb_vwv[ 1]=    0 (0x0)
smb_vwv[ 2]=    0 (0x0)
smb_bcc=29
[000] 55 6E 69 78 00 53 61 6D  62 61 20 33 2E 30 2E 31  Unix.Sam ba
3.0.1
[010] 34 61 2D 44 65 62 69 61  6E 00 5A 47 00           4a-Debia n.ZG.
30040: session setup ok
write_socket(3,64)
write_socket(3,64) wrote 64
read_socket_with_timeout: timeout read. EOF from client.
receive_smb_raw: length < 0!
client_receive_smb failed
30040: tree connect failed: Call returned zero bytes (EOF)
SMB connection failed

Thank you,
-Jiri

-- 
Jiri Klouda <jk at zg.cz>
http://www.zg.cz/~jk
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: Digital signature
Url : http://lists.samba.org/archive/samba/attachments/20050625/ee6fd474/attachment.bin

More information about the samba mailing list