magnum
2007-Aug-29 16:45 UTC
[Samba] Performance problem when copy from samba server to client
Hi All, I noticed slow performance when make file copy from server to client. From client to server it is OK. Also I tried mount locally on localhost - same results. Here is my smb.conf --------------------------------------------------------------------------------------------------------------------------------------------------- [global] # 1. Server Naming Options: # workgroup = NT-Domain-Name or Workgroup-Name workgroup = mm server string = rax load printers = no ; printing = cups log file = /var/log/samba/log.%m max log size = 5000 log level = 10 security = domain password server = * encrypt passwords = yes smb passwd file = /etc/samba/private/smbpasswd winbind uid = 10000-20000 winbind gid = 10000-20000 template homedir = /home/wins/%D/%U template shell = /bin/bash interfaces = 192.168.1.203/24 127.0.0.1/24 local master = no #============================ Share Definitions ============================= [homes] comment = Home Directories browseable = no writable = no [tmp] comment = Temporary file space path = /tmp read only = no public = yes _______________________________________________________________________________________________ _______________________________________________________________________________________________ [2007/08/23 16:29:59, 3] smbd/oplock.c:init_oplocks(863) init_oplocks: initializing messages. [2007/08/23 16:29:59, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(276) Linux kernel oplocks enabled [2007/08/23 16:29:59, 3] smbd/process.c:process_smb(1068) Transaction 0 of length 82 [2007/08/23 16:29:59, 3] smbd/process.c:switch_message(926) switch message SMBnegprot (pid 3096) conn 0x0 [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [LM1.2X002] [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [LANMAN2.1] [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [NT LM 0.12] [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [POSIX 2] [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_nt1(351) not using SPNEGO [2007/08/23 16:29:59, 3] smbd/negprot.c:reply_negprot(606) Selected protocol NT LM 0.12 [2007/08/23 16:29:59, 3] smbd/process.c:process_smb(1068) Transaction 1 of length 234 [2007/08/23 16:29:59, 3] smbd/process.c:switch_message(926) switch message SMBsesssetupX (pid 3096) conn 0x0 [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:29:59, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244) wct=13 flg2=0xd001 [2007/08/23 16:29:59, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1390) Domain=[mm] NativeOS=[Linux version 2.6.22.5] NativeLanMan=[CIFS VFS Client for Linux] PrimaryDomain=[] [2007/08/23 16:29:59, 2] smbd/sesssetup.c:setup_new_vc_session(1200) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2007/08/23 16:29:59, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1405) sesssetupX:name=[mm]\[vilnisr]@[127.0.0.1] [2007/08/23 16:29:59, 3] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: "PDC1, *" [2007/08/23 16:29:59, 3] libsmb/namequery.c:resolve_lmhosts(966) resolve_lmhosts: Attempting lmhosts lookup for name PDC1<0x20> [2007/08/23 16:29:59, 3] libsmb/namequery.c:resolve_wins(863) resolve_wins: Attempting wins lookup for name PDC1<0x20> [2007/08/23 16:29:59, 3] libsmb/namequery.c:resolve_wins(902) resolve_wins: using WINS server 192.168.1.7 and tag '*' [2007/08/23 16:29:59, 2] libsmb/namequery.c:name_query(604) Got a positive name query response from 192.168.1.7 ( 192.168.1.7 ) [2007/08/23 16:29:59, 3] libsmb/namequery_dc.c:rpc_dc_name(186) rpc_dc_name: Returning DC PDC1 (192.168.1.7) for domain MM [2007/08/23 16:29:59, 3] libsmb/cliconnect.c:cli_start_connection(1505) Connecting to host=PDC1 [2007/08/23 16:29:59, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 445 [2007/08/23 16:29:59, 2] lib/util_sock.c:open_socket_out(911) error connecting to 192.168.1.7:445 (Connection refused) [2007/08/23 16:29:59, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 139 [2007/08/23 16:29:59, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \lsarpc fnum 0x800 bind request returned ok. [2007/08/23 16:29:59, 3] rpc_parse/parse_lsa.c:lsa_io_sec_qos(224) lsa_io_sec_qos: length c does not match size 8 [2007/08/23 16:29:59, 3] auth/auth.c:check_ntlm_password(221) check_ntlm_password: Checking password for unmapped user [mm]\[vilnisr]@[127.0.0.1] with the new password interface [2007/08/23 16:29:59, 3] auth/auth.c:check_ntlm_password(224) check_ntlm_password: mapped user is: [MM]\[vilnisr]@[127.0.0.1] [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:29:59, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:29:59, 3] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: "PDC1, *" [2007/08/23 16:29:59, 3] libsmb/namequery_dc.c:rpc_dc_name(186) rpc_dc_name: Returning DC PDC1 (192.168.1.7) for domain MM [2007/08/23 16:29:59, 3] libsmb/cliconnect.c:cli_start_connection(1505) Connecting to host=PDC1 [2007/08/23 16:29:59, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 445 [2007/08/23 16:29:59, 2] lib/util_sock.c:open_socket_out(911) error connecting to 192.168.1.7:445 (Connection refused) [2007/08/23 16:29:59, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 139 [2007/08/23 16:29:59, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \NETLOGON fnum 0x804 bind request returned ok. [2007/08/23 16:29:59, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \NETLOGON fnum 0x805 bind request returned ok. [2007/08/23 16:29:59, 0] auth/auth_domain.c:domain_client_validate(257) domain_client_validate: unable to validate password for user vilnisr in domain MM to Domain controller PDC1. Error was NT_STATUS_WRONG_PASSWORD. [2007/08/23 16:29:59, 2] auth/auth.c:check_ntlm_password(319) check_ntlm_password: Authentication for user [vilnisr] -> [vilnisr] FAILED with error NT_STATUS_WRONG_PASSWORD [2007/08/23 16:29:59, 3] smbd/error.c:error_packet_set(106) error packet at smbd/sesssetup.c(1489) cmd=115 (SMBsesssetupX) NT_STATUS_LOGON_FAILURE [2007/08/23 16:29:59, 3] smbd/process.c:timeout_processing(1328) timeout_processing: End of file from client (client has disconnected). [2007/08/23 16:29:59, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:29:59, 3] smbd/connection.c:yield_connection(69) Yielding connection to [2007/08/23 16:29:59, 3] smbd/server.c:exit_server_common(768) Server exit (normal exit) [2007/08/23 16:30:25, 3] smbd/oplock.c:init_oplocks(863) init_oplocks: initializing messages. [2007/08/23 16:30:25, 3] smbd/oplock_linux.c:linux_init_kernel_oplocks(276) Linux kernel oplocks enabled [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 0 of length 82 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBnegprot (pid 3100) conn 0x0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [LM1.2X002] [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [LANMAN2.1] [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [NT LM 0.12] [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_negprot(505) Requested protocol [POSIX 2] [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_nt1(351) not using SPNEGO [2007/08/23 16:30:25, 3] smbd/negprot.c:reply_negprot(606) Selected protocol NT LM 0.12 [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 1 of length 234 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBsesssetupX (pid 3100) conn 0x0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244) wct=13 flg2=0xd001 [2007/08/23 16:30:25, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1390) Domain=[mm] NativeOS=[Linux version 2.6.22.5] NativeLanMan=[CIFS VFS Client for Linux] PrimaryDomain=[] [2007/08/23 16:30:25, 2] smbd/sesssetup.c:setup_new_vc_session(1200) setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources. [2007/08/23 16:30:25, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1405) sesssetupX:name=[mm]\[vilnisr]@[127.0.0.1] [2007/08/23 16:30:25, 3] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: "PDC1, *" [2007/08/23 16:30:25, 3] libsmb/namequery_dc.c:rpc_dc_name(186) rpc_dc_name: Returning DC PDC1 (192.168.1.7) for domain MM [2007/08/23 16:30:25, 3] libsmb/cliconnect.c:cli_start_connection(1505) Connecting to host=PDC1 [2007/08/23 16:30:25, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 445 [2007/08/23 16:30:25, 2] lib/util_sock.c:open_socket_out(911) error connecting to 192.168.1.7:445 (Connection refused) [2007/08/23 16:30:25, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 139 [2007/08/23 16:30:25, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \lsarpc fnum 0x3813 bind request returned ok. [2007/08/23 16:30:25, 3] rpc_parse/parse_lsa.c:lsa_io_sec_qos(224) lsa_io_sec_qos: length c does not match size 8 [2007/08/23 16:30:25, 3] auth/auth.c:check_ntlm_password(221) check_ntlm_password: Checking password for unmapped user [mm]\[vilnisr]@[127.0.0.1] with the new password interface [2007/08/23 16:30:25, 3] auth/auth.c:check_ntlm_password(224) check_ntlm_password: mapped user is: [MM]\[vilnisr]@[127.0.0.1] [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: "PDC1, *" [2007/08/23 16:30:25, 3] libsmb/namequery_dc.c:rpc_dc_name(186) rpc_dc_name: Returning DC PDC1 (192.168.1.7) for domain MM [2007/08/23 16:30:25, 3] libsmb/cliconnect.c:cli_start_connection(1505) Connecting to host=PDC1 [2007/08/23 16:30:25, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 445 [2007/08/23 16:30:25, 2] lib/util_sock.c:open_socket_out(911) error connecting to 192.168.1.7:445 (Connection refused) [2007/08/23 16:30:25, 3] lib/util_sock.c:open_socket_out(874) Connecting to 192.168.1.7 at port 139 [2007/08/23 16:30:25, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \NETLOGON fnum 0xa805 bind request returned ok. [2007/08/23 16:30:25, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081) rpc_pipe_bind: Remote machine PDC1 pipe \NETLOGON fnum 0x4823 bind request returned ok. [2007/08/23 16:30:25, 3] auth/auth.c:check_ntlm_password(270) check_ntlm_password: winbind authentication for user [vilnisr] succeeded [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 2] auth/auth.c:check_ntlm_password(309) check_ntlm_password: authentication for user [vilnisr] -> [vilnisr] -> [vilnisr] succeeded [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089) fetch gid from cache 10001 -> S-1-5-32-544 [2007/08/23 16:30:25, 3] passdb/lookup_sid.c:fetch_gid_from_cache(1089) fetch gid from cache 10002 -> S-1-5-32-545 [2007/08/23 16:30:25, 3] lib/privileges.c:get_privileges(261) get_privileges: No privileges assigned to SID [S-1-5-21-852334022-912643877-2881777060-3266] [2007/08/23 16:30:25, 3] lib/privileges.c:get_privileges(261) get_privileges: No privileges assigned to SID [S-1-22-2-100] [2007/08/23 16:30:25, 3] lib/privileges.c:get_privileges(261) get_privileges: No privileges assigned to SID [S-1-5-2] [2007/08/23 16:30:25, 3] lib/privileges.c:get_privileges(261) get_privileges: No privileges assigned to SID [S-1-5-11] [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:push_sec_ctx(208) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/uid.c:push_conn_ctx(358) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:pop_sec_ctx(356) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/password.c:register_vuid(280) User name: vilnisr Real name: [2007/08/23 16:30:25, 3] smbd/password.c:register_vuid(301) UNIX uid 1133 is UNIX user vilnisr, and will be vuid 100 [2007/08/23 16:30:25, 3] smbd/password.c:register_vuid(332) Adding homes service for user 'vilnisr' using home directory: '/home/vilnisr' [2007/08/23 16:30:25, 3] param/loadparm.c:lp_add_home(2660) adding home's share [vilnisr] for user 'vilnisr' at '/home/vilnisr' [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 2 of length 86 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtconX (pid 3100) conn 0x0 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/service.c:make_connection_snum(806) Connect path is '/tmp' for service [tmp] [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(250) [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(251) se_access_check: user sid is S-1-5-21-852334022-912643877-2881777060-3266 se_access_check: also S-1-22-2-100 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 [2007/08/23 16:30:25, 3] smbd/vfs.c:vfs_init_default(95) Initialising default vfs hooks [2007/08/23 16:30:25, 3] smbd/vfs.c:vfs_init_custom(128) Initialising custom vfs hooks from [/[Default VFS]/] [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(250) [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(251) se_access_check: user sid is S-1-5-21-852334022-912643877-2881777060-3266 se_access_check: also S-1-22-2-100 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(250) [2007/08/23 16:30:25, 3] lib/util_seaccess.c:se_access_check(251) se_access_check: user sid is S-1-5-21-852334022-912643877-2881777060-3266 se_access_check: also S-1-22-2-100 se_access_check: also S-1-1-0 se_access_check: also S-1-5-2 se_access_check: also S-1-5-11 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (1133, 100) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 1] smbd/service.c:make_connection_snum(1033) 127.0.0.1 (127.0.0.1) connect to service tmp initially as user vilnisr (uid=1133, gid=100) (pid 3100) [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/reply.c:reply_tcon_and_X(574) tconX service=tmp [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 3 of length 72 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:25, 3] smbd/sec_ctx.c:set_sec_ctx(241) setting sec ctx (1133, 100) - sec_ctx_stack_ndx = 0 [2007/08/23 16:30:25, 3] smbd/trans2.c:call_trans2qfsinfo(2246) call_trans2qfsinfo: level = 260 [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 4 of length 72 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:25, 3] smbd/trans2.c:call_trans2qfsinfo(2246) call_trans2qfsinfo: level = 261 [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 5 of length 72 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:25, 3] smbd/trans2.c:call_trans2qfsinfo(2246) call_trans2qfsinfo: level = 512 [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 6 of length 86 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:25, 3] smbd/process.c:process_smb(1068) Transaction 7 of length 78 [2007/08/23 16:30:25, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:25, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:25, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:34, 3] smbd/process.c:process_smb(1068) Transaction 8 of length 78 [2007/08/23 16:30:34, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:34, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:34, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:35, 3] smbd/process.c:process_smb(1068) Transaction 9 of length 78 [2007/08/23 16:30:35, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:35, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:35, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:35, 3] smbd/process.c:process_smb(1068) Transaction 10 of length 88 [2007/08/23 16:30:35, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:35, 3] smbd/trans2.c:call_trans2findfirst(1703) call_trans2findfirst: dirtype = 17, maxentries = 150, close_after_first=0, close_if_end = 2 requires_resume_key = 4 level = 0x202, max_data_bytes = 16384 [2007/08/23 16:30:35, 3] smbd/dir.c:dptr_create(516) creating new dirptr 256 for path ./, expect_close = 1 [2007/08/23 16:30:42, 3] smbd/process.c:process_smb(1068) Transaction 11 of length 78 [2007/08/23 16:30:42, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:42, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:42, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo . (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 12 of length 116 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:45, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo austrumi-1.5.1.iso (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 13 of length 128 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBntcreateX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/dosmode.c:unix_mode(142) unix_mode(austrumi-1.5.1.iso) returning 0744 [2007/08/23 16:30:45, 2] smbd/open.c:open_file(391) vilnisr opened file austrumi-1.5.1.iso read=Yes write=No (numopen=1) [2007/08/23 16:30:45, 3] smbd/oplock_linux.c:linux_set_kernel_oplock(180) linux_set_kernel_oplock: got kernel oplock on file austrumi-1.5.1.iso, dev = 901, inode = 354, file_id = 1 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 14 of length 116 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBtrans2 (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/trans2.c:call_trans2qfilepathinfo(3252) call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 512 [2007/08/23 16:30:45, 3] smbd/trans2.c:call_trans2qfilepathinfo(3314) call_trans2qfilepathinfo austrumi-1.5.1.iso (fnum = -1) level=512 call=5 total_data=0 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 15 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 16 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 17 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 18 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 19 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 20 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 21 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 22 of length 63 [2007/08/23 16:30:45, 3] smbd/process.c:switch_message(926) switch message SMBreadX (pid 3100) conn 0x80461658 [2007/08/23 16:30:45, 3] smbd/reply.c:send_file_readX(2640) send_file_readX fnum=6889 max=16384 nread=16384 [2007/08/23 16:30:45, 3] smbd/process.c:process_smb(1068) Transaction 23 of length 63 --------------------------------------------------------------------------------------------------------------------------------------------- Any help appreciated
Apparently Analagous Threads
Wisdom of the Ancients
