Hi all,
recently I asked for help with a problem Samba had to write files
(http://lists.samba.org/pipermail/samba/2002-November/085003.html,
http://lists.samba.org/pipermail/samba/2002-November/085083.html).
Since that hasn't solved, and since it occurs regardless of changes
of the smb.conf, I'd like to post some passages of the smb log file
(log level 4) of the attempt to copy a file to a share mounted
locally by smbmount (which was quitted with an input/output error).
I hope it is not too much log text, but I do not know what could
be important.
The mounts were t that time ::
ufa@chlorine:~> mount
/dev/hda2 on / type reiserfs (rw)
proc on /proc type proc (rw)
devpts on /dev/pts type devpts (rw)
/dev/user/data_vol on /home type reiserfs (rw)
/dev/var/var_vol on /var type reiserfs (rw)
shmfs on /dev/shm type shm (rw)
usbdevfs on /proc/bus/usb type usbdevfs (rw)
//chlorine/homes on /mnt/pdc type smbfs (0)
ufa@chlorine:~>
The command was
chlorine:/etc/samba # cp smb.conf /mnt/pdc
(executed with uid 0)
The original file:
-rw-r--r-- 1 root root 11659 Nov 22 09:18 smb.conf
After that there was an empty file smb.conf in /home/ufa:
chlorine:/home/ufa # ls -l smb.conf
-rw-r--r-- 1 ufa users 0 Nov 22 09:23 smb.conf
The directory /home/ufa was writeable for ufa:
chlorine:/home/ufa # ls -al
total 633
drwxr-xr-x 7 ufa users 896 Nov 22 09:20 .
----start of transaction log --------------------------------------
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 6 of length 80
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBtrans2 (pid 31438)
[2002/11/22 09:23:24, 4] smbd/uid.c:change_to_user(119)
change_to_user: Skipping user change - already user
[2002/11/22 09:23:24, 3] smbd/trans2.c:call_trans2qfilepathinfo(1702)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1
[2002/11/22 09:23:24, 3] lib/util.c:unix_clean_name(387)
unix_clean_name [/]
[2002/11/22 09:23:24, 3] lib/util.c:unix_clean_name(387)
unix_clean_name [.]
[2002/11/22 09:23:24, 3] smbd/trans2.c:call_trans2qfilepathinfo(1734)
call_trans2qfilepathinfo . level=1 call=5 total_data=0
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 7 of length 88
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBtrans2 (pid 31438)
[2002/11/22 09:23:24, 4] smbd/uid.c:change_to_user(119)
change_to_user: Skipping user change - already user
[2002/11/22 09:23:24, 3] smbd/trans2.c:call_trans2qfilepathinfo(1702)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1
[2002/11/22 09:23:24, 3] lib/util.c:unix_clean_name(387)
unix_clean_name [/smb.conf]
[2002/11/22 09:23:24, 3] lib/util.c:unix_clean_name(387)
unix_clean_name [smb.conf]
[2002/11/22 09:23:24, 3] smbd/trans2.c:call_trans2qfilepathinfo(1734)
call_trans2qfilepathinfo smb.conf level=1 call=5 total_data=0
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
.....
Transaction 10 of length 88
.....
change_to_user: Skipping user change - already user
[2002/11/22 09:23:24, 3] smbd/dosmode.c:unix_mode(111)
unix_mode(smb.conf) returning 0744
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 1 of length 168
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBnegprot (pid 31450)
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [PC NETWORK PROGRAM 1.0]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [MICROSOFT NETWORKS 1.03]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [MICROSOFT NETWORKS 3.0]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [LANMAN1.0]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [LM1.2X002]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(342)
Requested protocol [Samba]
[2002/11/22 09:23:24, 3] smbd/negprot.c:reply_negprot(426)
Selected protocol NT LANMAN 1.0
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 2 of length 95
....
[2002/11/22 09:23:24, 3] smbd/reply.c:reply_sesssetup_and_X(858)
Domain=[FALKEN] NativeOS=[Unix] NativeLanMan=[Samba]
[2002/11/22 09:23:24, 3] smbd/reply.c:reply_sesssetup_and_X(868)
sesssetupX:name=[ufa]
[2002/11/22 09:23:24, 2] smbd/reply.c:reply_sesssetup_and_X(982)
Defaulting to Lanman password for ufa
[2002/11/22 09:23:24, 4] passdb/pass_check.c:pass_check(719)
pass_check: Checking (PAM) password for user ufa (l=7)
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_start(455)
smb_pam_start: PAM: Init user: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_start(472)
smb_pam_start: PAM: setting rhost to: chlorine.dl.ac.uk
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_start(481)
smb_pam_start: PAM: setting tty
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_start(489)
smb_pam_start: PAM: Init passed for user: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_auth(506)
smb_pam_auth: PAM: Authenticate User: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_auth(534)
smb_pam_auth: PAM: User ufa Authenticated OK
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_account(555)
smb_pam_account: PAM: Account Management for User: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_account(579)
smb_pam_account: PAM: Account OK for User: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_setcred(606)
PAM: Account Management SetCredentials for User: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_setcred(626)
smb_pam_setcred: PAM: SetCredentials OK for User: ufa
[2002/11/22 09:23:24, 4] passdb/pampass.c:smb_pam_end(436)
smb_pam_end: PAM: PAM_END OK.
[2002/11/22 09:23:24, 3] param/loadparm.c:lp_add_home(1991)
adding home directory ufa at /home/ufa
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:push_sec_ctx(297)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2002/11/22 09:23:24, 3] smbd/uid.c:push_conn_ctx(286)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 10 groups: 100, 0, 6, 8, 9, 14, 16, 17,
31, 33
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 10 groups: 100, 0, 6, 8, 9, 14, 16, 17,
31, 33
[2002/11/22 09:23:24, 3] smbd/password.c:register_vuid(336)
uid 500 registered to name ufa
[2002/11/22 09:23:24, 3] smbd/password.c:register_vuid(338)
Clearing default real name
[2002/11/22 09:23:24, 3] smbd/password.c:register_vuid(340)
User name: ufa Real name: Uwe Falke
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 3 of length 71
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBtconX (pid 31450)
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 4] smbd/reply.c:reply_tcon_and_X(334)
Got device type ?????
[2002/11/22 09:23:24, 3] smbd/password.c:authorise_login(736)
authorise_login: ACCEPTED: validated uid ok as non-guest (user=ufa)
[2002/11/22 09:23:24, 3] smbd/service.c:make_connection(487)
Connect path is /home/ufa
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:push_sec_ctx(297)
push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2002/11/22 09:23:24, 3] smbd/uid.c:push_conn_ctx(286)
push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 10 groups: 100, 0, 6, 8, 9, 14, 16, 17,
31, 33
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:get_current_groups(172)
get_current_groups: user is in 10 groups: 100, 0, 6, 8, 9, 14, 16, 17,
31, 33
[2002/11/22 09:23:24, 4]
rpc_server/srv_srvsvc_nt.c:get_share_security(211)
get_share_security: using default secdesc for ufa
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(269)
se_access_check: user sid is
S-1-5-21-2516889344-3334932691-3153003715-2000
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1201
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1001
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1013
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1017
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1019
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1029
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1033
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1035
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1063
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-21-2516889344-3334932691-3153003715-1067
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-1-0
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-2
[2002/11/22 09:23:24, 3] lib/util_seaccess.c:se_access_check(273)
se_access_check: also S-1-5-11
[2002/11/22 09:23:24, 3] smbd/vfs.c:vfs_init_default(123)
Initialising default vfs hooks
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (500, 100) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(334)
10 user groups:
100 0 6 8 9 14 16 17 31 33
[2002/11/22 09:23:24, 3] smbd/vfs.c:vfs_ChDir(570)
vfs_ChDir to /home/ufa
[2002/11/22 09:23:24, 1] smbd/service.c:make_connection(636)
chlorine (193.62.117.17) connect to service ufa as user ufa (uid=500,
gid=100) (pid 31450)
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/reply.c:reply_tcon_and_X(395)
tconX service=homes user=ufa
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 4 of length 53
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBsetattrE (pid 31450)
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
setting sec ctx (500, 100) - sec_ctx_stack_ndx = 0
[2002/11/22 09:23:24, 3] smbd/sec_ctx.c:set_sec_ctx(334)
10 user groups:
100 0 6 8 9 14 16 17 31 33
[2002/11/22 09:23:24, 3] smbd/error.c:error_packet(126)
error packet at smbd/reply.c(4852) cmd=34 (SMBsetattrE) eclass=1
ecode=6
[2002/11/22 09:23:24, 3] smbd/process.c:process_smb(878)
Transaction 5 of length 45
[2002/11/22 09:23:24, 3] smbd/process.c:switch_message(685)
switch message SMBclose (pid 31450)
[2002/11/22 09:23:24, 4] smbd/uid.c:change_to_user(119)
change_to_user: Skipping user change - already user
[2002/11/22 09:23:24, 3] smbd/error.c:error_packet(126)
error packet at smbd/reply.c(3135) cmd=4 (SMBclose) eclass=1 ecode=6
[2002/11/22 09:24:24, 3] smbd/sec_ctx.c:set_sec_ctx(329)
-------------------------------------end of transaction log---------
If someone (I think especially of those with some glue to the
source code) knows what could be causing the
smbd/error.c:error_packet(126) messages and how to overcome that I would
be glad to hear about.
Many thanx
Uwe
Dr. Uwe Falke
UK SuperSTEM Laboratory
Dept. of Engineering, University of Liverpool
c/o CCLRC Daresbury Laboratory,
Warrington, Cheshire, WA4 4AD
phone: 01925 86 4905
fax: 01925 86 4910
