streams_xattr problem
Puneet Arora
arorap at gmail.com
Thu Aug 16 05:14:49 MDT 2012
Hello,
I'm running Samba 3.6.6-1 on my x64 Arch Linux installation. I recently
decided to have my samba share support NTFS's Alternate Data Streams by
using streams_xattr or streams_depot. With streams_xattr I discovered that
if I copy a file having an ADS from a win7 box, on the linux console, I can
see the ADS as an xattr attached to the file. However when I copy the file
back to my win7 box using windows explorer, there are no ADS entries on it.
This is not true if I use streams_depot.
To this email, I've appended logs (log level=3) generated when I copy the
file back using windows explorer. I haven't been able to make much sense of
them or spot anything that's causing streams_xattr to not function
correctly. Also, looking at wireshark captured network traffic I see that
in response to QUERY_FILE_INFO (Query File Stream Info), Samba does not
return all streams associated with the file. The response consists of only
one stream, which is the file data. (At this point I'ven't looked at any
Samba code. If no has any idea what's going on here I'd be curious to look.
Could someone give pointers though about where to look?)
thanks,
Puneet
PS: On a side not, when I first enabled streams_xattr (true about
streams_depot too), I was getting an NT_STATUS_NETWORK_BUSY from smbd which
was also causing windows to report an 'unexpected error'. Upon some
research I found this: https://bugzilla.samba.org/show_bug.cgi?id=7537 .
Upon disbaleing kernel oplocks, this error was gone. I'm wondering if I
could still have the kernel oplocks on and there's another way to solve it,
since that bug is 2 years old.
--
[2012/08/06 00:53:26.317876, 3] smbd/process.c:1662(process_smb)
Transaction 303 of length 112 (0 toread)
[2012/08/06 00:53:26.318270, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.318527, 3]
smbd/trans2.c:5117(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2012/08/06 00:53:26.318716, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [bar.jpg] [/media/test1]
[2012/08/06 00:53:26.319162, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: bar.jpg reduced to /media/test1/bar.jpg
[2012/08/06 00:53:26.319530, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = -1) level=1004 call=5
total_data=0
[2012/08/06 00:53:26.320235, 3] smbd/process.c:1662(process_smb)
Transaction 304 of length 112 (0 toread)
[2012/08/06 00:53:26.320635, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.321164, 3]
smbd/trans2.c:5117(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1005
[2012/08/06 00:53:26.321555, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [bar.jpg] [/media/test1]
[2012/08/06 00:53:26.321748, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: bar.jpg reduced to /media/test1/bar.jpg
[2012/08/06 00:53:26.321966, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = -1) level=1005 call=5
total_data=0
[2012/08/06 00:53:26.322980, 3] smbd/process.c:1662(process_smb)
Transaction 305 of length 112 (0 toread)
[2012/08/06 00:53:26.323299, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.323474, 3]
smbd/trans2.c:5117(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2012/08/06 00:53:26.323664, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [bar.jpg] [/media/test1]
[2012/08/06 00:53:26.323897, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: bar.jpg reduced to /media/test1/bar.jpg
[2012/08/06 00:53:26.324212, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = -1) level=1004 call=5
total_data=0
[2012/08/06 00:53:26.349891, 3] smbd/process.c:1662(process_smb)
Transaction 306 of length 90 (0 toread)
[2012/08/06 00:53:26.350056, 3] smbd/process.c:1467(switch_message)
switch message SMBntcreateX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.350222, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [.] [/media/test1]
[2012/08/06 00:53:26.350382, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: . reduced to /media/test1
[2012/08/06 00:53:26.350558, 3] smbd/dosmode.c:159(unix_mode)
unix_mode(.) returning 0744
[2012/08/06 00:53:26.351819, 3] smbd/process.c:1662(process_smb)
Transaction 307 of length 76 (0 toread)
[2012/08/06 00:53:26.352145, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.352319, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1005
[2012/08/06 00:53:26.352529, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo . (fnum = 10395) level=1005 call=7 total_data=0
[2012/08/06 00:53:26.353184, 3] smbd/process.c:1662(process_smb)
Transaction 308 of length 45 (0 toread)
[2012/08/06 00:53:26.353491, 3] smbd/process.c:1467(switch_message)
switch message SMBclose (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.353658, 3] smbd/reply.c:4838(reply_close)
close directory fnum=10395
[2012/08/06 00:53:26.356075, 3] smbd/process.c:1662(process_smb)
Transaction 309 of length 45 (0 toread)
[2012/08/06 00:53:26.356396, 3] smbd/process.c:1467(switch_message)
switch message SMBclose (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.356659, 3] smbd/reply.c:4848(reply_close)
close fd=29 fnum=10393 (numopen=2)
[2012/08/06 00:53:26.357058, 2] smbd/close.c:696(close_normal_file)
puneet closed file bar.jpg (numopen=1) NT_STATUS_OK
[2012/08/06 00:53:26.357970, 3] smbd/process.c:1662(process_smb)
Transaction 310 of length 122 (0 toread)
[2012/08/06 00:53:26.358292, 3] smbd/process.c:1467(switch_message)
switch message SMBntcreateX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.358601, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [bar.jpg] [/media/test1]
[2012/08/06 00:53:26.358951, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: bar.jpg reduced to /media/test1/bar.jpg
[2012/08/06 00:53:26.359315, 3] smbd/dosmode.c:159(unix_mode)
unix_mode(bar.jpg) returning 0744
[2012/08/06 00:53:26.359607, 2] smbd/open.c:704(open_file)
puneet opened file bar.jpg read=Yes write=No (numopen=2)
[2012/08/06 00:53:26.360502, 3] smbd/process.c:1662(process_smb)
Transaction 311 of length 76 (0 toread)
[2012/08/06 00:53:26.360864, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.361054, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
[2012/08/06 00:53:26.361258, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = 10396) level=1004 call=7
total_data=0
[2012/08/06 00:53:26.361935, 3] smbd/process.c:1662(process_smb)
Transaction 312 of length 90 (0 toread)
[2012/08/06 00:53:26.362099, 3] smbd/process.c:1467(switch_message)
switch message SMBnttrans (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.362252, 3] smbd/error.c:81(error_packet_set)
error packet at smbd/nttrans.c(2519) cmd=160 (SMBnttrans)
NT_STATUS_NOT_SUPPORTED
[2012/08/06 00:53:26.362784, 3] smbd/process.c:1662(process_smb)
Transaction 313 of length 74 (0 toread)
[2012/08/06 00:53:26.363105, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.363281, 3] smbd/trans2.c:3513(call_trans2qfsinfo)
call_trans2qfsinfo: level = 261
[2012/08/06 00:53:26.363441, 3] smbd/trans2.c:2951(smbd_do_qfsinfo)
smbd_do_qfsinfo: level = 261
[2012/08/06 00:53:26.363998, 3] smbd/process.c:1662(process_smb)
Transaction 314 of length 76 (0 toread)
[2012/08/06 00:53:26.364399, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.364823, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
[2012/08/06 00:53:26.365262, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = 10396) level=1004 call=7
total_data=0
[2012/08/06 00:53:26.366166, 3] smbd/process.c:1662(process_smb)
Transaction 315 of length 114 (0 toread)
[2012/08/06 00:53:26.366340, 3] smbd/process.c:1467(switch_message)
switch message SMBntcreateX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.367029, 3] smbd/vfs.c:905(check_reduced_name)
check_reduced_name [desktop.ini] [/media/test1]
[2012/08/06 00:53:26.367287, 3] smbd/vfs.c:1039(check_reduced_name)
check_reduced_name: desktop.ini reduced to /media/test1/desktop.ini
[2012/08/06 00:53:26.367698, 3] smbd/dosmode.c:159(unix_mode)
unix_mode(desktop.ini) returning 0744
[2012/08/06 00:53:26.368086, 3] smbd/error.c:81(error_packet_set)
error packet at smbd/error.c(161) cmd=162 (SMBntcreateX)
NT_STATUS_OBJECT_NAME_NOT_FOUND
[2012/08/06 00:53:26.369614, 3] smbd/process.c:1662(process_smb)
Transaction 316 of length 76 (0 toread)
[2012/08/06 00:53:26.369929, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.370305, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1022
[2012/08/06 00:53:26.370813, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = 10396) level=1022 call=7
total_data=0
[2012/08/06 00:53:26.371812, 3] smbd/process.c:1662(process_smb)
Transaction 317 of length 76 (0 toread)
[2012/08/06 00:53:26.371988, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.372361, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1004
[2012/08/06 00:53:26.372774, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = 10396) level=1004 call=7
total_data=0
[2012/08/06 00:53:26.373533, 3] smbd/process.c:1662(process_smb)
Transaction 318 of length 76 (0 toread)
[2012/08/06 00:53:26.373714, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.374169, 3]
smbd/trans2.c:5032(call_trans2qfilepathinfo)
call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1005
[2012/08/06 00:53:26.374607, 3]
smbd/trans2.c:5261(call_trans2qfilepathinfo)
call_trans2qfilepathinfo bar.jpg (fnum = 10396) level=1005 call=7
total_data=0
[2012/08/06 00:53:26.377940, 3] smbd/process.c:1662(process_smb)
Transaction 319 of length 74 (0 toread)
[2012/08/06 00:53:26.378261, 3] smbd/process.c:1467(switch_message)
switch message SMBtrans2 (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.378688, 3] smbd/trans2.c:3513(call_trans2qfsinfo)
call_trans2qfsinfo: level = 259
[2012/08/06 00:53:26.379090, 3] smbd/trans2.c:2951(smbd_do_qfsinfo)
smbd_do_qfsinfo: level = 259
[2012/08/06 00:53:26.380242, 3] lib/sysquotas.c:432(sys_get_quota)
sys_get_vfs_quota() failed for mntpath[/media/test1]
bdev[/dev/mapper/test1] qtype[2] id[1000]: Operation not permitted
[2012/08/06 00:53:26.381325, 3] lib/sysquotas.c:432(sys_get_quota)
sys_get_vfs_quota() failed for mntpath[/media/test1]
bdev[/dev/mapper/test1] qtype[4] id[100]: Operation not permitted
[2012/08/06 00:53:26.382136, 3] smbd/process.c:1662(process_smb)
Transaction 320 of length 63 (0 toread)
[2012/08/06 00:53:26.382386, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.382884, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=32768 nread=32768
[2012/08/06 00:53:26.383828, 3] smbd/process.c:1662(process_smb)
Transaction 321 of length 63 (0 toread)
[2012/08/06 00:53:26.384164, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.384668, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=32768 nread=32768
[2012/08/06 00:53:26.385541, 3] smbd/process.c:1662(process_smb)
Transaction 322 of length 63 (0 toread)
[2012/08/06 00:53:26.385794, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.386274, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=32768 nread=32768
[2012/08/06 00:53:26.387140, 3] smbd/process.c:1662(process_smb)
Transaction 323 of length 63 (0 toread)
[2012/08/06 00:53:26.387392, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.387904, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=32768 nread=32768
[2012/08/06 00:53:26.388746, 3] smbd/process.c:1662(process_smb)
Transaction 324 of length 63 (0 toread)
[2012/08/06 00:53:26.388987, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.389460, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=32768 nread=32768
[2012/08/06 00:53:26.390271, 3] smbd/process.c:1662(process_smb)
Transaction 325 of length 63 (0 toread)
[2012/08/06 00:53:26.390536, 3] smbd/process.c:1467(switch_message)
switch message SMBreadX (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:26.390983, 3] smbd/reply.c:3702(send_file_readX)
send_file_readX fnum=10396 max=3613 nread=3613
[2012/08/06 00:53:37.009501, 3] smbd/process.c:1662(process_smb)
Transaction 326 of length 45 (0 toread)
[2012/08/06 00:53:37.009834, 3] smbd/process.c:1467(switch_message)
switch message SMBclose (pid 12102) conn 0x7f02fcec2aa0
[2012/08/06 00:53:37.010091, 3] smbd/reply.c:4848(reply_close)
close fd=29 fnum=10396 (numopen=2)
[2012/08/06 00:53:37.010918, 2] smbd/close.c:696(close_normal_file)
puneet closed file bar.jpg (numopen=1) NT_STATUS_OK
More information about the samba-technical
mailing list