[Samba] Samba serving sshfs shares: can't delete files

TLoD,Snake snake at tsn.spb.ru
Wed Jun 1 04:02:52 MDT 2011


On 05/31/2011 10:26 PM, Jeremy Allison wrote:
> On Tue, May 31, 2011 at 07:43:16PM +0400, TLoD,Snake wrote:
>> Hello!
>>
>> I have samba share on my sshfs-mounted folder. All works just fine
>> except I can't delete files from sshfs unless they are in 0777 chmodded
>> directory. Even if that files were putted trough smbclient. I can read
>> files, write files (regardless their directory permissions) but not
>> delete them.
>>
>> Here is my share config:
>>
>> [myshare]
>> comment = shre over sshfs
>> path = /home/kli/work/remotes/dev
>> valid users = kli
>> public = no
>> writable = yes
>> printable = no
>> delete readonly = yes
>> read only = no
>> force group = kli
>> force user = kli
> 
> Post a debug level 10 log snippet of smbclient attempting
> to delete files.

[2011/06/01 13:57:16,  3] param/loadparm.c:9039(lp_load_ex)
  lp_load_ex: refreshing parameters
[2011/06/01 13:57:16,  3] param/loadparm.c:4848(init_globals)
  Initialising global parameters
[2011/06/01 13:57:16,  2] param/loadparm.c:4707(max_open_files)
  rlimit_max: rlimit_max (1024) below minimum Windows limit (16384)
[2011/06/01 13:57:16,  3] ../lib/util/params.c:550(pm_process)
  params.c:pm_process() - Processing configuration file
"/etc/samba/smb.conf"
[2011/06/01 13:57:16,  3] param/loadparm.c:7726(do_section)
  Processing section "[global]"
  doing parameter workgroup = MYGROUP
  doing parameter server string = SSS Snakes Samba Server
  doing parameter security = user
  doing parameter load printers = yes
  doing parameter log file = /var/log/samba/log.%m
  doing parameter max log size = 50
  doing parameter local master = no
  doing parameter domain master = no
  doing parameter dns proxy = no
[2011/06/01 13:57:16,  4] param/loadparm.c:9074(lp_load_ex)
  pm_process() returned Yes
[2011/06/01 13:57:16,  7] param/loadparm.c:9279(lp_servicenumber)
  lp_servicenumber: couldn't find homes
[2011/06/01 13:57:16, 10] param/loadparm.c:8287(set_server_role)
  set_server_role: role = ROLE_STANDALONE
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS-2LE
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS-2LE
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-16LE
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-16LE
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS-2BE
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS-2BE
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-16BE
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-16BE
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF8
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF8
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-8
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-8
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset ASCII
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset ASCII
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset 646
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset 646
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset ISO-8859-1
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset ISO-8859-1
[2011/06/01 13:57:16,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS2-HEX
[2011/06/01 13:57:16,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS2-HEX
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:16,  2] lib/interface.c:340(add_interface)
  added interface eth0 ip=fe80::21e:8cff:feca:1076%eth0
bcast=fe80::ffff:ffff:ffff:ffff%eth0 netmask=ffff:ffff:ffff:ffff::
[2011/06/01 13:57:16,  2] lib/interface.c:340(add_interface)
  added interface eth0 ip=192.168.102.170 bcast=192.168.103.255
netmask=255.255.248.0
[2011/06/01 13:57:16,  5] lib/util.c:266(init_names)
  Netbios name list:-
  my_netbios_names[0]="SNAKE"
[2011/06/01 13:57:16,  3] client/client.c:4982(main)
  Client started (version 3.4.12).
[2011/06/01 13:57:24,  5] lib/gencache.c:61(gencache_init)
  Opening cache file at /var/cache/samba/gencache.tdb
[2011/06/01 13:57:24,  2] lib/util_tdb.c:385(tdb_log)
  tdb(unnamed): tdb_open_ex: could not open file
/var/cache/samba/gencache.tdb: Permission denied
[2011/06/01 13:57:24,  5] lib/gencache.c:69(gencache_init)
  gencache_init: Opening cache file /var/cache/samba/gencache.tdb read-only.
[2011/06/01 13:57:24, 10] lib/gencache.c:194(gencache_get)
  Cache entry with key = AD_SITENAME/DOMAIN/ couldn't be found
[2011/06/01 13:57:24,  5] libads/dns.c:814(sitename_fetch)
  sitename_fetch: No stored sitename for
[2011/06/01 13:57:24, 10] libsmb/namequery.c:1506(internal_resolve_name)
  internal_resolve_name: looking up localhost#20 (sitename (null))
[2011/06/01 13:57:24, 10] lib/gencache.c:194(gencache_get)
  Cache entry with key = NBT/LOCALHOST#20 couldn't be found
[2011/06/01 13:57:24,  5] libsmb/namecache.c:208(namecache_fetch)
  no entry for localhost#20 found.
[2011/06/01 13:57:24,  3] libsmb/namequery.c:1225(resolve_lmhosts)
  resolve_lmhosts: Attempting lmhosts lookup for name localhost<0x20>
[2011/06/01 13:57:24,  4] libsmb/namequery.c:909(getlmhostsent)
  getlmhostsent: lmhost entry: 127.0.0.1 localhost
[2011/06/01 13:57:24, 10] libsmb/namequery.c:583(remove_duplicate_addrs2)
  remove_duplicate_addrs2: looking for duplicate address/port pairs
[2011/06/01 13:57:24,  5] libsmb/namecache.c:122(namecache_store)
  namecache_store: storing 1 address for localhost#20: 127.0.0.1
[2011/06/01 13:57:24, 10] lib/gencache.c:131(gencache_set)
  Adding cache entry with key = NBT/LOCALHOST#20; value = 127.0.0.1:0
and timeout = Wed Jun  1 14:08:24 2011
   (660 seconds ahead)
[2011/06/01 13:57:24, 10] libsmb/namequery.c:1653(internal_resolve_name)
  internal_resolve_name: returning 1 addresses: 127.0.0.1:0
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Added timed event "tevent_req_timedout": 0xb77df968
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Added timed event "tevent_req_timedout": 0xb77dfdd0
[2011/06/01 13:57:24, 10] lib/events.c:113(run_events)
  Running timed event "tevent_req_timedout" 0xb77df968
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Destroying timer event 0xb77df968 "tevent_req_timedout"
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Added timed event "tevent_req_timedout": 0xb77df968
[2011/06/01 13:57:24,  3] lib/util_sock.c:1038(open_socket_out_send)
  Connecting to 127.0.0.1 at port 445
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Added timed event "tevent_req_timedout": 0xb77e06b0
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Destroying timer event 0xb77e06b0 "tevent_req_timedout"
[2011/06/01 13:57:24, 10] lib/events.c:295(s3_event_debug)
  s3_event: Destroying timer event 0xb77df968 "tevent_req_timedout"
[2011/06/01 13:57:24,  5] lib/util_sock.c:371(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 0
  	SO_REUSEADDR = 0
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 0
  	IPTOS_THROUGHPUT = 0
  	SO_SNDBUF = 50556
  	SO_RCVBUF = 87840
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
[2011/06/01 13:57:24,  4] libsmb/clidfs.c:185(do_connect)
   session request ok
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24,  5] lib/charcnv.c:82(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=1
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 1
[2011/06/01 13:57:24,  3] libsmb/cliconnect.c:940(cli_session_setup_spnego)
  Doing spnego session setup (blob length=58)
[2011/06/01 13:57:24,  3] libsmb/cliconnect.c:967(cli_session_setup_spnego)
  got OID=1.3.6.1.4.1.311.2.2.10
[2011/06/01 13:57:24,  3] libsmb/cliconnect.c:975(cli_session_setup_spnego)
  got principal=NONE
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,164)
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,164) wrote 164
[2011/06/01 13:57:24, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 240
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=240
  smb_com=0x73
  smb_rcls=22
  smb_reh=0
  smb_err=49152
  smb_flg=136
  smb_flg2=51201
  smb_tid=0
  smb_pid=32715
  smb_uid=100
  smb_mid=2
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=  145 (0x91)
  smb_bcc=197
[2011/06/01 13:57:24, 10] ../lib/util/util.c:304(_dump_data)
  [0000] A1 81 8E 30 81 8B A0 03   0A 01 01 A1 0C 06 0A 2B   ...0....
.......+
  [0010] 06 01 04 01 82 37 02 02   0A A2 76 04 74 4E 54 4C   .....7..
..v.tNTL
  [0020] 4D 53 53 50 00 02 00 00   00 0A 00 0A 00 30 00 00   MSSP....
.....0..
  [0030] 00 15 82 8A 60 E8 FD AA   06 FC 77 46 F5 00 00 00   ....`...
..wF....
  [0040] 00 00 00 00 00 3A 00 3A   00 3A 00 00 00 53 00 4E   .....:.:
.:...S.N
  [0050] 00 41 00 4B 00 45 00 02   00 0A 00 53 00 4E 00 41   .A.K.E..
...S.N.A
  [0060] 00 4B 00 45 00 01 00 0A   00 53 00 4E 00 41 00 4B   .K.E....
.S.N.A.K
  [0070] 00 45 00 04 00 00 00 03   00 12 00 6C 00 6F 00 63   .E......
...l.o.c
  [0080] 00 61 00 6C 00 68 00 6F   00 73 00 74 00 00 00 00   .a.l.h.o
.s.t....
  [0090] 00 55 00 6E 00 69 00 78   00 00 00 53 00 61 00 6D   .U.n.i.x
...S.a.m
  [00A0] 00 62 00 61 00 20 00 33   00 2E 00 34 00 2E 00 31   .b.a. .3
...4...1
  [00B0] 00 32 00 00 00 4D 00 59   00 47 00 52 00 4F 00 55   .2...M.Y
.G.R.O.U
  [00C0] 00 50 00 00 00                                    .P...
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=240
  smb_com=0x73
  smb_rcls=22
  smb_reh=0
  smb_err=49152
  smb_flg=136
  smb_flg2=51201
  smb_tid=0
  smb_pid=32715
  smb_uid=100
  smb_mid=2
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=  145 (0x91)
  smb_bcc=197
[2011/06/01 13:57:24, 10] ../lib/util/util.c:304(_dump_data)
  [0000] A1 81 8E 30 81 8B A0 03   0A 01 01 A1 0C 06 0A 2B   ...0....
.......+
  [0010] 06 01 04 01 82 37 02 02   0A A2 76 04 74 4E 54 4C   .....7..
..v.tNTL
  [0020] 4D 53 53 50 00 02 00 00   00 0A 00 0A 00 30 00 00   MSSP....
.....0..
  [0030] 00 15 82 8A 60 E8 FD AA   06 FC 77 46 F5 00 00 00   ....`...
..wF....
  [0040] 00 00 00 00 00 3A 00 3A   00 3A 00 00 00 53 00 4E   .....:.:
.:...S.N
  [0050] 00 41 00 4B 00 45 00 02   00 0A 00 53 00 4E 00 41   .A.K.E..
...S.N.A
  [0060] 00 4B 00 45 00 01 00 0A   00 53 00 4E 00 41 00 4B   .K.E....
.S.N.A.K
  [0070] 00 45 00 04 00 00 00 03   00 12 00 6C 00 6F 00 63   .E......
...l.o.c
  [0080] 00 61 00 6C 00 68 00 6F   00 73 00 74 00 00 00 00   .a.l.h.o
.s.t....
  [0090] 00 55 00 6E 00 69 00 78   00 00 00 53 00 61 00 6D   .U.n.i.x
...S.a.m
  [00A0] 00 62 00 61 00 20 00 33   00 2E 00 34 00 2E 00 31   .b.a. .3
...4...1
  [00B0] 00 32 00 00 00 4D 00 59   00 47 00 52 00 4F 00 55   .2...M.Y
.G.R.O.U
  [00C0] 00 50 00 00 00                                    .P...
[2011/06/01 13:57:24,  3] libsmb/ntlmssp.c:1023(ntlmssp_client_challenge)
  Got challenge flags:
[2011/06/01 13:57:24,  3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x608a8215
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_CHAL_ACCEPT_RESPONSE
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_CHAL_TARGET_INFO
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
[2011/06/01 13:57:24,  3] libsmb/ntlmssp.c:1045(ntlmssp_client_challenge)
  NTLMSSP: Set final flags:
[2011/06/01 13:57:24,  3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088215
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
[2011/06/01 13:57:24,  5] libsmb/ntlmssp.c:1119(ntlmssp_client_challenge)
  NTLMSSP challenge set by NTLM2
[2011/06/01 13:57:24,  5] libsmb/ntlmssp.c:1120(ntlmssp_client_challenge)
  challenge is:
[2011/06/01 13:57:24,  5] ../lib/util/util.c:304(_dump_data)
  [0000] 68 FD 4B D0 FB 9F 22 D6                            h.K...".
[2011/06/01 13:57:24,  3] libsmb/ntlmssp_sign.c:342(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2011/06/01 13:57:24,  3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088215
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_SIGN
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_KEY_EXCH
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,266)
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,266) wrote 266
[2011/06/01 13:57:24, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 104
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=104
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=0
  smb_pid=32715
  smb_uid=100
  smb_mid=3
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    9 (0x9)
  smb_bcc=61
[2011/06/01 13:57:24, 10] ../lib/util/util.c:304(_dump_data)
  [0000] A1 07 30 05 A0 03 0A 01   00 55 00 6E 00 69 00 78   ..0.....
.U.n.i.x
  [0010] 00 00 00 53 00 61 00 6D   00 62 00 61 00 20 00 33   ...S.a.m
.b.a. .3
  [0020] 00 2E 00 34 00 2E 00 31   00 32 00 00 00 4D 00 59   ...4...1
.2...M.Y
  [0030] 00 47 00 52 00 4F 00 55   00 50 00 00 00           .G.R.O.U .P...
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=104
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=0
  smb_pid=32715
  smb_uid=100
  smb_mid=3
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    9 (0x9)
  smb_bcc=61
[2011/06/01 13:57:24, 10] ../lib/util/util.c:304(_dump_data)
  [0000] A1 07 30 05 A0 03 0A 01   00 55 00 6E 00 69 00 78   ..0.....
.U.n.i.x
  [0010] 00 00 00 53 00 61 00 6D   00 62 00 61 00 20 00 33   ...S.a.m
.b.a. .3
  [0020] 00 2E 00 34 00 2E 00 31   00 32 00 00 00 4D 00 59   ...4...1
.2...M.Y
  [0030] 00 47 00 52 00 4F 00 55   00 50 00 00 00           .G.R.O.U .P...
[2011/06/01 13:57:24, 10] libsmb/clientgen.c:467(cli_init_creds)
  cli_init_creds: user kli domain MYGROUP
[2011/06/01 13:57:24,  0] libsmb/clidfs.c:233(do_connect)
  Domain=[MYGROUP] OS=[Unix] Server=[Samba 3.4.12]
[2011/06/01 13:57:24,  4] libsmb/clidfs.c:239(do_connect)
   session setup ok
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=4
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 4
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=104
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=8
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=5
  smt_wct=15
  smb_vwv[ 0]=   36 (0x24)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=16644 (0x4104)
  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]=   36 (0x24)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=  104 (0x68)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=   16 (0x10)
  smb_bcc=39
[2011/06/01 13:57:24, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 44 20 03 00 5C 00 6C   00 6F 00 63 00 61 00 6C   .D ..\.l
.o.c.a.l
  [0010] 00 68 00 6F 00 73 00 74   00 5C 00 70 00 72 00 69   .h.o.s.t
.\.p.r.i
  [0020] 00 61 00 6E 00 00 00                              .a.n...
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,108)
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,108) wrote 108
[2011/06/01 13:57:24, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 35
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x32
  smb_rcls=37
  smb_reh=2
  smb_err=49152
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=5
  smt_wct=0
  smb_bcc=0
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x32
  smb_rcls=37
  smb_reh=2
  smb_err=49152
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=5
  smt_wct=0
  smb_bcc=0
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,39)
[2011/06/01 13:57:24,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,39) wrote 39
[2011/06/01 13:57:24, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 35
[2011/06/01 13:57:24,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:24,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=6
  smt_wct=0
  smb_bcc=0
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=7
[2011/06/01 13:57:24, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 7
[2011/06/01 13:57:24,  4] libsmb/clidfs.c:282(do_connect)
   tconx ok
[2011/06/01 13:57:30,  3] lib/util.c:736(dos_clean_name)
  dos_clean_name [\examplelk.ru\]
[2011/06/01 13:57:30,  3] lib/util.c:788(unix_clean_name)
  unix_clean_name [\examplelk.ru\]
[2011/06/01 13:57:30,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:30,  5] lib/util.c:642(show_msg)
  size=98
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=8
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=8
  smt_wct=15
  smb_vwv[ 0]=   30 (0x1E)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=16644 (0x4104)
  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]=   30 (0x1E)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=   98 (0x62)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=33
[2011/06/01 13:57:30, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 44 20 01 01 00 00 00   00 5C 00 70 00 72 00 69   .D .....
.\.p.r.i
  [0010] 00 61 00 6E 00 6C 00 6B   00 2E 00 72 00 75 00 00   .a.n.l.k
...r.u..
  [0020] 00                                                .
[2011/06/01 13:57:30,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,102)
[2011/06/01 13:57:30,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,102) wrote 102
[2011/06/01 13:57:30, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 96
[2011/06/01 13:57:30,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:30,  5] lib/util.c:642(show_msg)
  size=96
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=8
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=   36 (0x24)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   36 (0x24)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=41
[2011/06/01 13:57:30, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 00 80 E9 52   26 A3 1F CC 01 80 F8 5F   .......R
&......_
  [0010] 4D 41 20 CC 01 80 E9 52   26 A3 1F CC 01 80 E9 52   MA ....R
&......R
  [0020] 26 A3 1F CC 01 10 00 00   00                       &....... .
[2011/06/01 13:57:30,  5] lib/util.c:632(show_msg)
[2011/06/01 13:57:30,  5] lib/util.c:642(show_msg)
  size=96
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=8
  smt_wct=10
  smb_vwv[ 0]=    2 (0x2)
  smb_vwv[ 1]=   36 (0x24)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    2 (0x2)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   36 (0x24)
  smb_vwv[ 7]=   60 (0x3C)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=41
[2011/06/01 13:57:30, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 00 00 00 00 80 E9 52   26 A3 1F CC 01 80 F8 5F   .......R
&......_
  [0010] 4D 41 20 CC 01 80 E9 52   26 A3 1F CC 01 80 E9 52   MA ....R
&......R
  [0020] 26 A3 1F CC 01 10 00 00   00                       &....... .
[2011/06/01 13:58:10,  3] lib/util.c:736(dos_clean_name)
  dos_clean_name [\examplelk.ru\aaa]
[2011/06/01 13:58:10,  3] lib/util.c:788(unix_clean_name)
  unix_clean_name [\examplelk.ru\aaa]
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=9
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 9
[2011/06/01 13:58:10,  1] client/client.c:1680(do_put)
  putting file /home/snake/autoprice.xls as \examplelk.ru\aaa
cli_chain_cork: mid=10
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=11
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=12
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=13
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=14
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 10
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=15
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 11
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=16
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 12
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=17
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 13
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=18
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 14
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=19
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 15
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=20
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 16
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=21
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 17
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=22
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 18
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=23
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 19
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=24
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 20
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=25
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 21
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 22
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 23
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 24
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 25
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:597(cli_chain_cork)
  cli_chain_cork: mid=26
[2011/06/01 13:58:10, 10] libsmb/async_smb.c:1028(handle_incoming_pdu)
  handle_incoming_pdu: got mid 26
[2011/06/01 13:58:10,  1] client/client.c:1716(do_put)
  (9500.0 kb/s) (average 9500.0 kb/s)
[2011/06/01 13:58:13,  5] lib/util.c:632(show_msg)
[2011/06/01 13:58:13,  5] lib/util.c:642(show_msg)
  size=112
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=8
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=27
  smt_wct=15
  smb_vwv[ 0]=   44 (0x2C)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=   10 (0xA)
  smb_vwv[ 3]=16644 (0x4104)
  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]=   44 (0x2C)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=  112 (0x70)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    1 (0x1)
  smb_bcc=47
[2011/06/01 13:58:13, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 44 20 06 00 56 05 06   00 04 01 00 00 00 00 5C   .D ..V..
.......\
  [0010] 00 70 00 72 00 69 00 61   00 6E 00 6C 00 6B 00 2E   .p.r.i.a
.n.l.k..
  [0020] 00 72 00 75 00 5C 00 61   00 61 00 61 00 00 00     .r.u.\.a .a.a...
[2011/06/01 13:58:13,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,116)
[2011/06/01 13:58:13,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,116) wrote 116
[2011/06/01 13:58:13, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 168
[2011/06/01 13:58:13,  5] lib/util.c:632(show_msg)
[2011/06/01 13:58:13,  5] lib/util.c:642(show_msg)
  size=168
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=27
  smt_wct=10
  smb_vwv[ 0]=   10 (0xA)
  smb_vwv[ 1]=  100 (0x64)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=   10 (0xA)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  100 (0x64)
  smb_vwv[ 7]=   68 (0x44)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=113
[2011/06/01 13:58:13, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 FD FF 01 00 01 00 00   00 00 00 00 00 64 00 00   ........
.....d..
  [0010] 00 00 00 00 00 00 10 14   7A 41 20 CC 01 00 10 14   ........ zA
.....
  [0020] 7A 41 20 CC 01 00 65 B0   69 42 20 CC 01 00 65 B0   zA ...e. iB
...e.
  [0030] 69 42 20 CC 01 00 6E 1E   00 00 00 00 00 00 70 1E   iB ...n.
......p.
  [0040] 00 00 00 00 00 20 00 00   00 06 00 00 00 00 00 00   ..... ..
........
  [0050] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........
........
  [0060] 00 00 00 00 00 00 00 00   00 00 00 61 00 61 00 61   ........
...a.a.a
  [0070] 00                                                .
[2011/06/01 13:58:13,  5] lib/util.c:632(show_msg)
[2011/06/01 13:58:13,  5] lib/util.c:642(show_msg)
  size=168
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=27
  smt_wct=10
  smb_vwv[ 0]=   10 (0xA)
  smb_vwv[ 1]=  100 (0x64)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=   10 (0xA)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  100 (0x64)
  smb_vwv[ 7]=   68 (0x44)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=113
[2011/06/01 13:58:13, 10] ../lib/util/util.c:304(_dump_data)
  [0000] 00 FD FF 01 00 01 00 00   00 00 00 00 00 64 00 00   ........
.....d..
  [0010] 00 00 00 00 00 00 10 14   7A 41 20 CC 01 00 10 14   ........ zA
.....
  [0020] 7A 41 20 CC 01 00 65 B0   69 42 20 CC 01 00 65 B0   zA ...e. iB
...e.
  [0030] 69 42 20 CC 01 00 6E 1E   00 00 00 00 00 00 70 1E   iB ...n.
......p.
  [0040] 00 00 00 00 00 20 00 00   00 06 00 00 00 00 00 00   ..... ..
........
  [0050] 00 00 00 00 00 00 00 00   00 00 00 00 00 00 00 00   ........
........
  [0060] 00 00 00 00 00 00 00 00   00 00 00 61 00 61 00 61   ........
...a.a.a
  [0070] 00                                                .
[2011/06/01 13:58:13,  3] libsmb/clilist.c:451(cli_list_new)
  received 1 entries (eos=1)
[2011/06/01 13:58:13,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,74)
[2011/06/01 13:58:13,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,74) wrote 74
[2011/06/01 13:58:13, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 35
[2011/06/01 13:58:13,  5] lib/util.c:632(show_msg)
[2011/06/01 13:58:13,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x6
  smb_rcls=34
  smb_reh=0
  smb_err=49152
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=28
  smt_wct=0
  smb_bcc=0
[2011/06/01 13:58:13, 10] intl/lang_tdb.c:138(lang_tdb_init)
  lang_tdb_init: /usr/lib/samba/.msg: No such file or directory
[2011/06/01 13:58:15,  6] libsmb/clientgen.c:245(write_socket)
  write_socket(5,39)
[2011/06/01 13:58:15,  6] libsmb/clientgen.c:248(write_socket)
  write_socket(5,39) wrote 39
[2011/06/01 13:58:15, 10]
lib/util_sock.c:794(read_smb_length_return_keepalive)
  got smb length of 35
[2011/06/01 13:58:15,  5] lib/util.c:632(show_msg)
[2011/06/01 13:58:15,  5] lib/util.c:642(show_msg)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=32715
  smb_uid=100
  smb_mid=29
  smt_wct=0
  smb_bcc=0


More information about the samba mailing list