[Pkg-samba-maint] Bug#631364: Bug#631364:

Dani Daniel lindani at hotmail.de
Sat Jun 25 09:55:16 UTC 2011




> Date: Fri, 24 Jun 2011 20:01:31 +0200
> From: bubulle at debian.org
> To: lindani at hotmail.de
> CC: 631364 at bugs.debian.org
> Subject: Re: [Pkg-samba-maint] Bug#631364:
> 
> reassign 631364 samba
> found 631364 2:3.5.6~dfsg-3
> thanks
> 
> Quoting Dani Daniel (lindani at hotmail.de):
> 
> > > What is the version of samba on the server? 3.5.6 as in squeeze?
> > > 
> > > Can you reproduce this with another samba server, or with a share
> > > located on a Windows machine?
> > > 
> > > 
> > 
> > Yes, it's version 3.5.6 in squeeze. I tried it on a share exported with windows xp. There it doesn't happen, only on a share exported on my debian squeeze server.
> >  		 	   		  
> 
> Hmm, then the problem might be server-side.
> 
> Could you look in the samba server's logfile? You may need to increase
> the log level to 3. Maybe 10...but that will be *very* verbose and
> you'd then need to try isolating the issue as narrowly as possible.
> 
> 
> 
> -- 
> 
> 

Ok, I here is my log file:

--------------------It begins:

[2011/06/25 12:35:59.876545,  3] smbd/process.c:1294(switch_message)
  switch message SMBreadX (pid 2337) conn 0xb87c9258
[2011/06/25 12:35:59.876571,  3] smbd/sec_ctx.c:310(set_sec_ctx)
  setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0
[2011/06/25 12:35:59.876591,  5] auth/token_util.c:531(debug_nt_user_token)
  NT user token of user S-1-5-21-3324774100-1771195128-357238496-1001
  contains 25 SIDs
  SID[  0]: S-1-5-21-3324774100-1771195128-357238496-1001
  SID[  1]: S-1-22-2-1000
  SID[  2]: S-1-1-0
  SID[  3]: S-1-5-2
  SID[  4]: S-1-5-11
  SID[  5]: S-1-22-2-24
  SID[  6]: S-1-22-2-25
  SID[  7]: S-1-22-2-29
  SID[  8]: S-1-22-2-30
  SID[  9]: S-1-22-2-44
  SID[ 10]: S-1-22-2-46
  SID[ 11]: S-1-22-2-108
  SID[ 12]: S-1-22-2-109
  SID[ 13]: S-1-22-2-115
  SID[ 14]: S-1-22-2-1500
  SID[ 15]: S-1-22-2-1501
  SID[ 16]: S-1-22-2-1502
  SID[ 17]: S-1-22-2-1503
  SID[ 18]: S-1-22-2-1505
  SID[ 19]: S-1-22-2-1504
  SID[ 20]: S-1-22-2-1506
  SID[ 21]: S-1-22-2-1507
  SID[ 22]: S-1-22-2-1508
  SID[ 23]: S-1-22-2-1509
  SID[ 24]: S-1-22-1-1000
  SE_PRIV  0x0 0x0 0x0 0x0
[2011/06/25 12:35:59.876821,  5] auth/token_util.c:551(debug_unix_user_token)
  UNIX token of user 1000
  Primary group is 1000 and contains 20 supplementary groups
  Group[  0]: 1000
  Group[  1]: 24
  Group[  2]: 25
  Group[  3]: 29
  Group[  4]: 30
  Group[  5]: 44
  Group[  6]: 46
  Group[  7]: 108
  Group[  8]: 109
  Group[  9]: 115
  Group[ 10]: 1500
  Group[ 11]: 1501
  Group[ 12]: 1502
  Group[ 13]: 1503
  Group[ 14]: 1505
  Group[ 15]: 1504
  Group[ 16]: 1506
  Group[ 17]: 1507
  Group[ 18]: 1508
  Group[ 19]: 1509
[2011/06/25 12:35:59.876974,  5] smbd/uid.c:354(change_to_user)
  change_to_user uid=(0,1000) gid=(0,1000)
[2011/06/25 12:35:59.877018, 10] locking/posix.c:301(is_posix_locked)
  is_posix_locked: File dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, offset = 9080832, count = 16384, type = READ
[2011/06/25 12:35:59.877057, 10] locking/posix.c:172(posix_lock_in_range)
  posix_lock_in_range: offset_out = 9080832, count_out = 16384
[2011/06/25 12:35:59.877080,  8] locking/posix.c:250(posix_fcntl_getlock)
  posix_fcntl_getlock 33 9080832 16384 0
[2011/06/25 12:35:59.877102,  8] lib/util.c:1822(fcntl_getlock)
  fcntl_getlock fd=33 offset=9080832 count=16384 type=0
[2011/06/25 12:35:59.877128,  3] lib/util.c:1846(fcntl_getlock)
  fcntl_getlock: fd 33 is returned info 2 pid 0
[2011/06/25 12:35:59.877147,  8] locking/posix.c:280(posix_fcntl_getlock)
  posix_fcntl_getlock: Lock query call successful
[2011/06/25 12:35:59.877166, 10] locking/brlock.c:1308(brl_locktest)
  brl_locktest: posix start=9080832 len=16384 unlocked for fnum 17196 file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:35:59.877193, 10] locking/locking.c:154(strict_lock_default)
  strict_lock_default: flavour = WINDOWS_LOCK brl start=9080832 len=16384 unlocked for fnum 17196 file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:35:59.877261, 10] smbd/fileio.c:106(read_file)
  read_file (dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp): pos = 9080832, size = 16384, returned 16384
[2011/06/25 12:35:59.877303,  3] smbd/reply.c:3591(send_file_readX)
  send_file_readX fnum=17196 max=16384 nread=16384
[2011/06/25 12:35:59.877878, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
  got smb length of 59
[2011/06/25 12:35:59.877926,  6] smbd/process.c:1482(process_smb)
  got message type 0x0 of len 0x3b
[2011/06/25 12:35:59.877947,  3] smbd/process.c:1485(process_smb)
  Transaction 801 of length 63 (0 toread)
[2011/06/25 12:35:59.877968,  5] lib/util.c:617(show_msg)
[2011/06/25 12:35:59.877980,  5] lib/util.c:627(show_msg)
  size=59
  smb_com=0x2e
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=2467
  smb_uid=100
  smb_mid=4403
  smt_wct=12
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=17196 (0x432C)
  smb_vwv[ 3]=53248 (0xD000)
  smb_vwv[ 4]=  138 (0x8A)
  smb_vwv[ 5]=16384 (0x4000)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_bcc=0
[2011/06/25 12:35:59.878121,  3] smbd/process.c:1294(switch_message)
  switch message SMBreadX (pid 2337) conn 0xb87c9258
[2011/06/25 12:35:59.878143,  4] smbd/uid.c:257(change_to_user)
  change_to_user: Skipping user change - already user
[2011/06/25 12:35:59.878175, 10] locking/posix.c:301(is_posix_locked)
  is_posix_locked: File dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, offset = 9097216, count = 16384, type = READ

-------------------------This repeats a lot of times... then it continues:

[2011/06/25 12:35:59.976141, 10] locking/locking.c:655(parse_share_modes)
  parse_share_modes: share_mode_entry[1]: UNUSED pid = 2337, share_access = 0x7, private_options = 0x40, access_mask = 0x120089, mid = 0x0, type= 0x40, gen_id = 9, uid = 1000, flags = 0, file_id fe01:d21836:0
[2011/06/25 12:35:59.976171, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 01FE0000000000003618
[2011/06/25 12:35:59.976213, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 01FE0000000000003618
[2011/06/25 12:35:59.976239, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87bd618
[2011/06/25 12:35:59.976259, 10] locking/brlock.c:1896(brl_get_locks_internal)
  brl_get_locks_internal: 1 current locks on file_id fe01:d21836:0
[2011/06/25 12:35:59.976282, 10] locking/brlock.c:48(print_lock_struct)
  [0]: smbpid = 2467, tid = 1, pid = 2337, start = 0, size = 9223372036854775808, fnum = 17196, WRITE WINDOWS_LOCK
[2011/06/25 12:35:59.976333, 10] locking/posix.c:480(reduce_windows_lock_ref_count)
  reduce_windows_lock_ref_count for file now dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp = 0
[2011/06/25 12:35:59.976364, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 01FE0000000000003618
[2011/06/25 12:35:59.976397, 10] locking/posix.c:512(get_windows_lock_ref_count)
  get_windows_lock_count for file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp = 0
[2011/06/25 12:35:59.976436, 10] locking/posix.c:666(fd_close_posix)
  fd_close_posix: doing close on 1 fd's.
[2011/06/25 12:35:59.976466, 10] locking/posix.c:538(delete_windows_lock_ref_count)
  delete_windows_lock_ref_count for file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:35:59.976493,  2] smbd/close.c:656(close_normal_file)
  dani closed file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp (numopen=0) NT_STATUS_OK
[2011/06/25 12:35:59.976519,  5] smbd/files.c:497(file_free)
  freed files structure 17196 (0 used)
[2011/06/25 12:35:59.976542,  5] lib/util.c:617(show_msg)
[2011/06/25 12:35:59.976555,  5] lib/util.c:627(show_msg)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=128
  smb_flg2=49155
  smb_tid=1
  smb_pid=2467
  smb_uid=100
  smb_mid=4481
  smt_wct=0
  smb_bcc=0
[2011/06/25 12:36:00.001821, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
  got smb length of 202
[2011/06/25 12:36:00.001865,  6] smbd/process.c:1482(process_smb)
  got message type 0x0 of len 0xca
[2011/06/25 12:36:00.001886,  3] smbd/process.c:1485(process_smb)
  Transaction 880 of length 206 (0 toread)
[2011/06/25 12:36:00.001905,  5] lib/util.c:617(show_msg)
[2011/06/25 12:36:00.001917,  5] lib/util.c:627(show_msg)
  size=202
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=0
  smb_flg2=49153
  smb_tid=1
  smb_pid=2467
  smb_uid=100
  smb_mid=4482
  smt_wct=24
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=30208 (0x7600)
  smb_vwv[ 3]=  512 (0x200)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=  192 (0xC0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=32768 (0x8000)
  smb_vwv[14]=    0 (0x0)
  smb_vwv[15]= 1792 (0x700)
  smb_vwv[16]=    0 (0x0)
  smb_vwv[17]=  256 (0x100)
  smb_vwv[18]=    0 (0x0)
  smb_vwv[19]=16384 (0x4000)
  smb_vwv[20]=    0 (0x0)
  smb_vwv[21]=  512 (0x200)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=  768 (0x300)
  smb_bcc=119
[2011/06/25 12:36:00.002116, 10] ../lib/util/util.c:278(_dump_data)
  [0000] 00 5C 00 64 00 61 00 6E   00 69 00 5C 00 73 00 63   .\.d.a.n .i.\.s.c
  [0010] 00 68 00 75 00 6C 00 65   00 5C 00 77 00 31 00 69   .h.u.l.e .\.w.1.i
  [0020] 00 6B 00 31 00 5C 00 69   00 74 00 73 00 5C 00 6C   .k.1.\.i .t.s.\.l
  [0030] 00 69 00 6E 00 75 00 78   00 2D 00 70 00 72 00 61   .i.n.u.x .-.p.r.a
  [0040] 00 65 00 73 00 65 00 6E   00 74 00 61 00 74 00 69   .e.s.e.n .t.a.t.i
  [0050] 00 6F 00 6E 00 5C 00 6C   00 69 00 6E 00 75 00 78   .o.n.\.l .i.n.u.x
  [0060] 00 2D 00 70 00 72 00 61   00 65 00 73 00 2E 00 6F   .-.p.r.a .e.s...o
  [0070] 00 64 00 70 00 00 00                              .d.p... 
[2011/06/25 12:36:00.002322,  3] smbd/process.c:1294(switch_message)
  switch message SMBntcreateX (pid 2337) conn 0xb87c9258
[2011/06/25 12:36:00.002355,  4] smbd/uid.c:257(change_to_user)
  change_to_user: Skipping user change - already user
[2011/06/25 12:36:00.002386, 10] smbd/nttrans.c:498(reply_ntcreate_and_X)
  reply_ntcreate_and_X: flags = 0x2, access_mask = 0xc0000000 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x40 root_dir_fid = 0x0, fname = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002415,  5] smbd/filename.c:169(unix_convert)
  unix_convert called on file "dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp"
[2011/06/25 12:36:00.002438,  5] smbd/filename.c:328(unix_convert)
  unix_convert begin: name = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, dirpath = , start = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002475,  5] smbd/filename.c:351(unix_convert)
  conversion of base_name finished dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp -> dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002518,  3] smbd/vfs.c:851(check_reduced_name)
  check_reduced_name [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] [/mnt/storage/dokumente]
[2011/06/25 12:36:00.002572, 10] smbd/vfs.c:938(check_reduced_name)
  check_reduced_name realpath [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] -> [/mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp]
[2011/06/25 12:36:00.002596,  3] smbd/vfs.c:1008(check_reduced_name)
  check_reduced_name: dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp reduced to /mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002619, 10] smbd/open.c:3410(create_file_default)
  create_file: access_mask = 0xc0000000 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x40 oplock_request = 0x1 root_dir_fid = 0x0, ea_list = 0x(nil), sd = 0x(nil), fname = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002649,  3] smbd/vfs.c:851(check_reduced_name)
  check_reduced_name [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] [/mnt/storage/dokumente]
[2011/06/25 12:36:00.002689, 10] smbd/vfs.c:938(check_reduced_name)
  check_reduced_name realpath [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] -> [/mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp]
[2011/06/25 12:36:00.002711,  3] smbd/vfs.c:1008(check_reduced_name)
  check_reduced_name: dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp reduced to /mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002733, 10] smbd/open.c:2928(create_file_unixpath)
  create_file_unixpath: access_mask = 0xc0000000 file_attributes = 0x80, share_access = 0x7, create_disposition = 0x1 create_options = 0x40 oplock_request = 0x1 ea_list = 0x(nil), sd = 0x(nil), fname = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002763,  5] smbd/files.c:119(file_new)
  allocated file structure 13103, fnum = 17199 (1 used)
[2011/06/25 12:36:00.002789,  2] smbd/dosmode.c:98(unix_mode)
  unix_mode(dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp) inheriting from dani/schule/w1ik1/its/linux-praesentation
[2011/06/25 12:36:00.002815,  2] smbd/dosmode.c:121(unix_mode)
  unix_mode(dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp) inherit mode 42750
[2011/06/25 12:36:00.002835,  3] smbd/dosmode.c:166(unix_mode)
  unix_mode(dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp) returning 0740
[2011/06/25 12:36:00.002855, 10] smbd/open.c:1526(open_file_ntcreate)
  open_file_ntcreate: fname=dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, dos_attrs=0x80 access_mask=0xc0000000 share_access=0x7 create_disposition = 0x1 create_options=0x40 unix mode=0740 oplock_request=1
[2011/06/25 12:36:00.002880,  3] smbd/vfs.c:851(check_reduced_name)
  check_reduced_name [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] [/mnt/storage/dokumente]
[2011/06/25 12:36:00.002920, 10] smbd/vfs.c:938(check_reduced_name)
  check_reduced_name realpath [dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp] -> [/mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp]
[2011/06/25 12:36:00.002941,  3] smbd/vfs.c:1008(check_reduced_name)
  check_reduced_name: dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp reduced to /mnt/storage/dokumente/dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002964,  8] smbd/dosmode.c:613(dos_mode)
  dos_mode: dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.002985,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning a
[2011/06/25 12:36:00.003006,  8] smbd/dosmode.c:666(dos_mode)
  dos_mode returning a
[2011/06/25 12:36:00.003029, 10] lib/util_seaccess.c:58(se_map_generic)
  se_map_generic(): mapped mask 0xc0000000 to 0x0012019f
[2011/06/25 12:36:00.003049, 10] smbd/open.c:1708(open_file_ntcreate)
  open_file_ntcreate: fname=dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, after mapping access_mask=0x12019f
[2011/06/25 12:36:00.003092, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 01FE0000000000003618
[2011/06/25 12:36:00.003126, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87bdec8
[2011/06/25 12:36:00.003148, 10] smbd/open.c:1033(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.003171, 10] smbd/open.c:1033(delay_for_oplocks)
  delay_for_oplocks: oplock type 0x1 on file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.003193,  4] smbd/open.c:1970(open_file_ntcreate)
  calling open_file with flags=0x2 flags2=0x0 mode=0740, access_mask = 0x12019f, open_access_mask = 0x12019f
[2011/06/25 12:36:00.003226, 10] smbd/open.c:169(fd_open)
  fd_open: name dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, flags = 02 mode = 0740, fd = 33. 
[2011/06/25 12:36:00.003250,  2] smbd/open.c:633(open_file)
  dani opened file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp read=Yes write=Yes (numopen=1)
[2011/06/25 12:36:00.003280,  3] smbd/oplock_linux.c:127(linux_set_kernel_oplock)
  linux_set_kernel_oplock: got kernel oplock on file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, file_id = fe01:d21836:0 gen_id = 10
[2011/06/25 12:36:00.003309,  5] smbd/oplock.c:86(set_file_oplock)
  set_file_oplock: granted oplock on file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, fe01:d21836:0/10, tv_sec = 4e05ba10, tv_usec = ac8
[2011/06/25 12:36:00.003340, 10] locking/locking.c:726(unparse_share_modes)
  unparse_share_modes: del: 0, owrt: Sa 25 Jun 2011 11:35:35 CEST CEST cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num: 1
[2011/06/25 12:36:00.003394, 10] locking/locking.c:518(print_share_mode_table)
  print_share_mode_table: share_mode_entry[0]:  pid = 2337, share_access = 0x7, private_options = 0x40, access_mask = 0x12019f, mid = 0x0, type= 0x1, gen_id = 10, uid = 1000, flags = 0, file_id fe01:d21836:0
[2011/06/25 12:36:00.003422, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 01FE0000000000003618
[2011/06/25 12:36:00.003456, 10] smbd/open.c:3235(create_file_unixpath)
  create_file_unixpath: info=1
[2011/06/25 12:36:00.003477, 10] smbd/open.c:3481(create_file_default)
  create_file: info=1
[2011/06/25 12:36:00.003499,  8] smbd/dosmode.c:613(dos_mode)
  dos_mode: dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.003520,  8] smbd/dosmode.c:207(dos_mode_from_sbuf)
  dos_mode_from_sbuf returning a
[2011/06/25 12:36:00.003542,  8] smbd/dosmode.c:666(dos_mode)
  dos_mode returning a
[2011/06/25 12:36:00.003570, 10] locking/locking.c:552(parse_share_modes)
  parse_share_modes: delete_on_close: 0, owrt: Sa 25 Jun 2011 11:35:35 CEST CEST, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
[2011/06/25 12:36:00.003620, 10] locking/locking.c:655(parse_share_modes)
  parse_share_modes: share_mode_entry[0]:  pid = 2337, share_access = 0x7, private_options = 0x40, access_mask = 0x12019f, mid = 0x0, type= 0x1, gen_id = 10, uid = 1000, flags = 0, file_id fe01:d21836:0
[2011/06/25 12:36:00.003647,  5] smbd/nttrans.c:722(reply_ntcreate_and_X)
  reply_ntcreate_and_X: fnum = 17199, open name = dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:00.004084, 10] lib/util_sock.c:731(read_smb_length_return_keepalive)
  got smb length of 71
[2011/06/25 12:36:00.004125,  6] smbd/process.c:1482(process_smb)
  got message type 0x0 of len 0x47
[2011/06/25 12:36:00.004147,  3] smbd/process.c:1485(process_smb)
  Transaction 881 of length 75 (0 toread)
[2011/06/25 12:36:00.004166,  5] lib/util.c:617(show_msg)
[2011/06/25 12:36:00.004179,  5] lib/util.c:627(show_msg)

.
.
.
-----------------------And ends:
  Primary group is 0 and contains 0 supplementary groups
[2011/06/25 12:36:18.408238,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2011/06/25 12:36:18.408269,  5] lib/messages.c:329(messaging_deregister)
  Deregistering messaging pointer for type 784 - private_data=0xb87cc2f8
[2011/06/25 12:36:18.408295,  4] smbd/vfs.c:721(vfs_ChDir)
  vfs_ChDir to /mnt/storage/bilder
[2011/06/25 12:36:18.408322,  3] smbd/sec_ctx.c:310(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/06/25 12:36:18.408351,  5] auth/token_util.c:525(debug_nt_user_token)
  NT user token: (NULL)
[2011/06/25 12:36:18.408370,  5] auth/token_util.c:551(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2011/06/25 12:36:18.408401,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2011/06/25 12:36:18.408422,  1] smbd/service.c:1251(close_cnum)
  __ffff_192.168.1.10 (::ffff:192.168.1.10) closed connection to service bilder
[2011/06/25 12:36:18.408446,  3] smbd/connection.c:31(yield_connection)
  Yielding connection to bilder
[2011/06/25 12:36:18.408546, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 21090000FFFFFFFF0200
[2011/06/25 12:36:18.408578, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87cd268
[2011/06/25 12:36:18.408608, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 21090000FFFFFFFF0200
[2011/06/25 12:36:18.408706,  4] smbd/vfs.c:721(vfs_ChDir)
  vfs_ChDir to /
[2011/06/25 12:36:18.408729,  3] smbd/sec_ctx.c:310(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/06/25 12:36:18.408749,  5] auth/token_util.c:525(debug_nt_user_token)
  NT user token: (NULL)
[2011/06/25 12:36:18.408767,  5] auth/token_util.c:551(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2011/06/25 12:36:18.408797,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2011/06/25 12:36:18.408827,  5] lib/messages.c:329(messaging_deregister)
  Deregistering messaging pointer for type 784 - private_data=0xb87c9788
[2011/06/25 12:36:18.408851,  4] smbd/vfs.c:721(vfs_ChDir)
  vfs_ChDir to /mnt/storage/dokumente
[2011/06/25 12:36:18.408879, 10] smbd/oplock_linux.c:139(linux_release_kernel_oplock)
  linux_release_kernel_oplock: file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp, file_id = fe01:d21836:0 gen_id = 10 has kernel oplock state of 1.
[2011/06/25 12:36:18.408928, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 01FE0000000000003618
[2011/06/25 12:36:18.408956, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87b0af0
[2011/06/25 12:36:18.408975, 10] locking/locking.c:552(parse_share_modes)
  parse_share_modes: delete_on_close: 0, owrt: Sa 25 Jun 2011 11:35:35 CEST CEST, cwrt: Do 01 Jan 1970 01:00:00 CET CET, tok: 0, num_share_modes: 1
[2011/06/25 12:36:18.409031, 10] locking/locking.c:655(parse_share_modes)
  parse_share_modes: share_mode_entry[0]:  pid = 2337, share_access = 0x7, private_options = 0x40, access_mask = 0x12019f, mid = 0x0, type= 0x1, gen_id = 10, uid = 1000, flags = 0, file_id fe01:d21836:0
[2011/06/25 12:36:18.409077, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 01FE0000000000003618
[2011/06/25 12:36:18.409124, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 01FE0000000000003618
[2011/06/25 12:36:18.409149, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb8745a80
[2011/06/25 12:36:18.409170, 10] locking/brlock.c:1896(brl_get_locks_internal)
  brl_get_locks_internal: 1 current locks on file_id fe01:d21836:0
[2011/06/25 12:36:18.409195, 10] locking/brlock.c:48(print_lock_struct)
  [0]: smbpid = 2467, tid = 1, pid = 2337, start = 0, size = 9223372036854775808, fnum = 17199, WRITE WINDOWS_LOCK
[2011/06/25 12:36:18.409243, 10] locking/posix.c:480(reduce_windows_lock_ref_count)
  reduce_windows_lock_ref_count for file now dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp = 0
[2011/06/25 12:36:18.409286, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 01FE0000000000003618
[2011/06/25 12:36:18.409321, 10] locking/posix.c:512(get_windows_lock_ref_count)
  get_windows_lock_count for file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp = 0
[2011/06/25 12:36:18.409345, 10] locking/posix.c:538(delete_windows_lock_ref_count)
  delete_windows_lock_ref_count for file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp
[2011/06/25 12:36:18.409386,  2] smbd/close.c:656(close_normal_file)
  dani closed file dani/schule/w1ik1/its/linux-praesentation/linux-praes.odp (numopen=0) NT_STATUS_OK
[2011/06/25 12:36:18.409411,  5] smbd/files.c:497(file_free)
  freed files structure 17199 (0 used)
[2011/06/25 12:36:18.409434,  3] smbd/sec_ctx.c:310(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/06/25 12:36:18.409454,  5] auth/token_util.c:525(debug_nt_user_token)
  NT user token: (NULL)
[2011/06/25 12:36:18.409473,  5] auth/token_util.c:551(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2011/06/25 12:36:18.409508,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2011/06/25 12:36:18.409531,  1] smbd/service.c:1251(close_cnum)
  __ffff_192.168.1.10 (::ffff:192.168.1.10) closed connection to service dokumente
[2011/06/25 12:36:18.409557,  3] smbd/connection.c:31(yield_connection)
  Yielding connection to dokumente
[2011/06/25 12:36:18.409661, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 21090000FFFFFFFF0100
[2011/06/25 12:36:18.409694, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87cd268
[2011/06/25 12:36:18.409724, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 21090000FFFFFFFF0100
[2011/06/25 12:36:18.409826,  4] smbd/vfs.c:721(vfs_ChDir)
  vfs_ChDir to /
[2011/06/25 12:36:18.409851,  3] smbd/sec_ctx.c:310(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2011/06/25 12:36:18.409871,  5] auth/token_util.c:525(debug_nt_user_token)
  NT user token: (NULL)
[2011/06/25 12:36:18.409889,  5] auth/token_util.c:551(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2011/06/25 12:36:18.409920,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2011/06/25 12:36:18.409951,  5] lib/messages.c:329(messaging_deregister)
  Deregistering messaging pointer for type 784 - private_data=0xb87cca20
[2011/06/25 12:36:18.418284, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 49442F323333372F3130
[2011/06/25 12:36:18.418429, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87b97f0
[2011/06/25 12:36:18.418456,  4] auth/pampass.c:472(smb_pam_start)
  smb_pam_start: PAM: Init user: dani
[2011/06/25 12:36:18.421009,  4] auth/pampass.c:489(smb_pam_start)
  smb_pam_start: PAM: setting rhost to: ::ffff:192.168.1.10
[2011/06/25 12:36:18.421080,  4] auth/pampass.c:498(smb_pam_start)
  smb_pam_start: PAM: setting tty
[2011/06/25 12:36:18.421101,  4] auth/pampass.c:506(smb_pam_start)
  smb_pam_start: PAM: Init passed for user: dani
[2011/06/25 12:36:18.421122,  4] auth/pampass.c:643(smb_internal_pam_session)
  smb_internal_pam_session: PAM: tty set to: smb/2337/100
[2011/06/25 12:36:18.422029,  4] auth/pampass.c:450(smb_pam_end)
  smb_pam_end: PAM: PAM_END OK.
[2011/06/25 12:36:18.422165, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 49442F323333372F3130
[2011/06/25 12:36:18.422226,  3] smbd/connection.c:31(yield_connection)
  Yielding connection to 
[2011/06/25 12:36:18.422401, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
  Locking key 21090000FFFFFFFFFFFF
[2011/06/25 12:36:18.422441, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
  Allocated locked data 0x0xb87ba508
[2011/06/25 12:36:18.422473, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
  Unlocking key 21090000FFFFFFFFFFFF
[2011/06/25 12:36:18.422844,  3] smbd/server.c:906(exit_server_common)
  Server exit (termination signal)
 		 	   		  
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.alioth.debian.org/pipermail/pkg-samba-maint/attachments/20110625/93bebeaf/attachment-0001.html>


More information about the Pkg-samba-maint mailing list