[Samba] Samba 2.2.8a and HP-UX 11.11 (11i)

Fri Jan 9 21:11:01 GMT 2004

I read a message from the archives that describes nearly our exact problem
that has begun to appear after 11i was installed on our server. File
access has become slow and unreliable, especially under high loads. As a
matter of fact, I had a logon script running a series of echo -- it was
executing them at about 1 line every 2 seconds. The way we "solved" the
problem was moving the Samba tree to a high-speed array; now it's flaky,
but faster at being flaky so it is not as noticeable. However, I'd
obviously like to correct the problem. The last gentleman who had this
problem never received a response (that was in 2001) -- let's see if
anyone has had any luck since.

I will include what I have determined to be relevant log entries -- though
I could be mistaken. Here is one PC's logon process:

  switch message SMBtrans2 (pid 3239)
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1034
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/JMP4XP/Jmp.exe]
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [JMP4XP/Jmp.exe]
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1729)
  call_trans2qfilepathinfo JMP4XP/Jmp.exe level=1034 call=5 total_data=0
[2004/01/09 15:56:10, 3] smbd/process.c:process_smb(846)
  Transaction 72896 of length 92
[2004/01/09 15:56:10, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 3239)
[2004/01/09 15:56:10, 3] smbd/sec_ctx.c:set_sec_ctx(328)
  setting sec ctx (2226, 254) - sec_ctx_stack_ndx = 0
[2004/01/09 15:56:10, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  3 user groups:
  254 405 417
[2004/01/09 15:56:10, 3] smbd/vfs.c:vfs_ChDir(574)
  vfs_ChDir to /users2/torresmm
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/moricons.dll]
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [moricons.dll]
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1719)
  call_trans2qfilepathinfo: vfs_stat of moricons.dll failed (No such file or directory)
[2004/01/09 15:56:10, 3] smbd/error.c:error_packet(94)
  error string = No such file or directory
[2004/01/09 15:56:10, 3] smbd/error.c:error_packet(109)
  error packet at smbd/trans2.c(1721) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND
[2004/01/09 15:56:10, 3] smbd/process.c:process_smb(846)
  Transaction 72897 of length 92
[2004/01/09 15:56:10, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 3239)
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/moricons.dll]
[2004/01/09 15:56:10, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [moricons.dll]
[2004/01/09 15:56:10, 3] smbd/trans2.c:call_trans2qfilepathinfo(1719)
  call_trans2qfilepathinfo: vfs_stat of moricons.dll failed (No such file or directory)
[2004/01/09 15:56:10, 3] smbd/error.c:error_packet(94)
  error string = No such file or directory
[2004/01/09 15:56:10, 3] smbd/error.c:error_packet(109)
  error packet at smbd/trans2.c(1721) cmd=50 (SMBtrans2) NT_STATUS_OBJECT_NAME_NOT_FOUND
[2004/01/09 15:57:10, 3] smbd/sec_ctx.c:set_sec_ctx(328)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0

...what is it doing looking for moricons.dll in the first place? I see
this all over my logs -- it looks for things like moricons.dll and
rundll32.dll on the Samba server in the first place?

The other one I see is that it appears to attempt to read files over and
over. Here is an example:

[2004/01/09 15:57:11, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] smbd/trans2.c:call_trans2qfilepathinfo(1729)
  call_trans2qfilepathinfo admin/umdnj2k.bmp level=1004 call=5 total_data=0
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59271 of length 97
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 10467)
[2004/01/09 15:57:11, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] smbd/trans2.c:call_trans2qfilepathinfo(1729)
  call_trans2qfilepathinfo admin/umdnj2k.bmp level=1004 call=5 total_data=0
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59272 of length 106
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 10467)
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(admin/umdnj2k.bmp) returning 0744
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 2] smbd/open.c:open_file(243)
  zoltekew opened file admin/umdnj2k.bmp read=Yes write=No (numopen=1)
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59273 of length 120
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 10467)
[2004/01/09 15:57:11, 3] smbd/trans2.c:call_trans2setfilepathinfo(2393)
  call_trans2setfilepathinfo(8) admin/umdnj2k.bmp info_level=1004 totdata=40
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59274 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=4096 nread=4096
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59275 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=2784 nread=2784
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59276 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=4096 nread=4096
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59277 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=4096 nread=4096
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59278 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=4096 nread=4096
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59279 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11677 max=4096 nread=4096
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59280 of length 45
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBclose (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:reply_close(3190)
  close fd=24 fnum=11677 (numopen=1)
[2004/01/09 15:57:11, 2] smbd/close.c:close_normal_file(227)
  zoltekew closed file admin/umdnj2k.bmp (numopen=0)
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59281 of length 106
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 10467)
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(admin/umdnj2k.bmp) returning 0744
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 2] smbd/open.c:open_file(243)
  zoltekew opened file admin/umdnj2k.bmp read=Yes write=No (numopen=1)
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59282 of length 45
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBclose (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:reply_close(3190)
  close fd=-1 fnum=11678 (numopen=1)
[2004/01/09 15:57:11, 2] smbd/close.c:close_normal_file(227)
  zoltekew closed file admin/umdnj2k.bmp (numopen=0)
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59283 of length 106
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 10467)
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(admin/umdnj2k.bmp) returning 0744
[2004/01/09 15:57:11, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [admin/umdnj2k.bmp]
[2004/01/09 15:57:11, 2] smbd/open.c:open_file(243)
  zoltekew opened file admin/umdnj2k.bmp read=Yes write=No (numopen=1)
[2004/01/09 15:57:11, 3] smbd/process.c:process_smb(846)
  Transaction 59284 of length 63
[2004/01/09 15:57:11, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:11, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59285 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59286 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59287 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59288 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59289 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59290 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=32768 nread=32768
[2004/01/09 15:57:12, 3] smbd/process.c:process_smb(846)
  Transaction 59291 of length 63
[2004/01/09 15:57:12, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 10467)
[2004/01/09 15:57:12, 3] smbd/reply.c:send_file_readX(2581)
  send_file_readX fnum=11679 max=6880 nread=6880
[2004/01/09 15:57:13, 3] smbd/process.c:process_smb(846)
  Transaction 59292 of length 74

...isn't there a lot of redundancy in there?

Environment:

HP-UX njmsa B.11.11 U 9000/800 627349383 unlimited-user license
Samba v2.2.8a / Windows XP client workstations

B3901BA  B.11.11.06  HP C/ANSI C Developer's Bundle for HP-UX 11.i (S800)
PHSS_28706  1.0            ANSI C compiler   B.11.11.06 cumulative patch

---- _  _ _  _ ___  _  _  _
|Y#| |  | |\/| |  \ |\ |  |  | Ryan Novosielski - Jr. UNIX Systems Admin
|$&| |__| |  | |__/ | \| _|  | novosirj at umdnj.edu - 973/972.0922 (2-0922)
\__/ Univ. of Med. and Dent. | IST/ACS - NJMS Medical Science Bldg - C630