[Samba] File copy error with Samba 2.2.x and 3.0/Novell/Win2k

Thu May 16 11:13:04 GMT 2002

Hello,

We're experiencing a problem with samba after an upgrade, which only occurs
when using a strange combination of software.

The problem only occurs with samba 2.2 and higher.  This problem does not
occur with samba 2.0.  We tested this with samba 3.0alpha17, 2.2.2, 
2.2.4, and 2.0.10

First off, sticking to procedure, TESTs 1-11 as described in DIAGNOSIS.txt 
succeed.

I've gone as far back as April 2001 in the mailing lists and not seen
the same problem.
I'm not experiencing the same symptoms as the mailing list post re:
    a "gotcha" regarding Samba, win2kSP2 and Novell Client 4.80SP3

If an attempt is made to copy a .htm, .html, or .doc file (any other 
types we tested did not reproduce the problem), using Windows Explorer
from a Windows 2000 system from a netware 5.1 EDIR file share to a 
samba share, the following occurs:

	1.  Regardless of whether or not the file previously existed on 
            the samba share, it would produce a prompt asking if we
            would like to replace the file, which has a datestamp of
            the current date and time, and has a length of 0 bytes.

	2.  When it is chosen to replace the file, the following 
	    popup error message occurs:  "Cannot copy <filename>: Access
            is denied.  The source file may be in use"

If the file did not originally exist on the samba share, it will now 
exist as 0 bytes in length.  If it did previously exist, it will have
been over-written as a 0 byte file.

This does not occur when using Windows Explorer from a Windows 9x machine.
This does not occur when using the command prompt to copy files.
This does not occur when copying from local drive to any drive.
This does not occur when copying from any drive to a Netware drive.
This does not occur when copying from any drive to a Windows 2000 drive.
This does not occur with most other files types (e.g. .ht, .jpg, or .h)

We have not tested to see if the same problem occurs with WinNT, or WinXP.
We have not tested with any other version of Netware.

We tried disabling web content displaying features on Windows Explorer.  The
problem still occurred.

We tried disabling all locking and oplock features on samba.  The problem
still occurred.

We tried setting samba's fstype to something other than NTFS.  The problem 
still occurred.

We tried deleting the Windows 2000 Registry key, NtfsEncryptionService, from
HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Control\FileSystem
as it is written as a solution for a similar error message on:
http://support.microsoft.com/default.aspx?scid=kb;EN-US;q302093
The problem still occurred.  (We didn't figure this one would work, but
we tried anyway)

I've also included a logfile piece that includes the log captured by samba
2.2.2 at log level = 10 which contains occurrences of the problem.

Thank you,
Duane

Logfile snippet:
------------------------------------------------------------------------

[2002/05/13 15:40:24, 10]
lib/util_sock.c:read_smb_length_return_keepalive(602)
  got smb length of 320
[2002/05/13 15:40:24, 6] smbd/process.c:process_smb(859)
  got message type 0x0 of len 0x140
[2002/05/13 15:40:24, 3] smbd/process.c:process_smb(860)
  Transaction 36 of length 324
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=320
  smb_com=0xa0
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=2055
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20736
  smt_wct=19
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[0]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[1]=23040 (0x5A00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[2]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[3]=38912 (0x9800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[4]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[5]=25856 (0x6500)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[6]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[7]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[8]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[9]=23040 (0x5A00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[10]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[11]=19456 (0x4C00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[12]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[13]=38912 (0x9800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[14]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[15]=43008 (0xA800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[16]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[17]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[18]=1 (0x1)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=247
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 68 00 10 00 00 00 00  00 00 00 96 01 13 00 00  .h...... ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 00 00 00 00 00 00 00 80  00 00 00 03 00 00 00 05  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 00 00 00 44 00 00 00 00  00 00 00 98 00 00 00 24  ...D.... .......$
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 00 00 00 02 00 00 00 03  00 5C 00 4C 00 61 00 62  ........ .\.L.a.b
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 00 35 00 43 00 69 00 72  00 63 00 75 00 69 00 74  .5.C.i.r .c.u.i.t
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 00 73 00 2E 00 68 00 74  00 6D 00 6C 00 00 00 00  .s...h.t .m.l....
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [060] 00 00 00 80 0F 80 00 4E  65 74 41 27 73 20 41 56  .......N etA's AV
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [070] 20 53 74 61 6D 70 00 01  00 00 00 BD EB 00 00 08   Stamp.. ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [080] 00 00 00 04 00 00 00 00  00 00 00 10 00 00 00 01  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [090] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0A0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0B0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0C0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0D0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0E0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0F0] 00 00 00 00 00 00 00                              ....... 
[2002/05/13 15:40:24, 3] smbd/process.c:switch_message(667)
  switch message SMBnttrans (pid 15241)
[2002/05/13 15:40:24, 4] smbd/uid.c:become_user(119)
  Skipping become_user - already user
[2002/05/13 15:40:24, 10] smbd/nttrans.c:reply_nttrans(1858)
  reply_nttrans: parameter_count = 90
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 10 00 00 00 00 00 00 00  96 01 13 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 00 00 00 00 80 00 00 00  03 00 00 00 05 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 44 00 00 00 00 00 00 00  98 00 00 00 24 00 00 00  D....... ....$...
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 02 00 00 00 03 00 5C 00  4C 00 61 00 62 00 35 00  ......\. L.a.b.5.
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 43 00 69 00 72 00 63 00  75 00 69 00 74 00 73 00  C.i.r.c. u.i.t.s.
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 2E 00 68 00 74 00 6D 00  6C 00                    ..h.t.m. l.
[2002/05/13 15:40:24, 10] smbd/nttrans.c:reply_nttrans(1863)
  reply_nttrans: data_count = 152
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 00 00 00 80 0F 80 00  4E 65 74 41 27 73 20 41  ........ NetA's A
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 56 20 53 74 61 6D 70 00  01 00 00 00 BD EB 00 00  V Stamp. ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 08 00 00 00 04 00 00 00  00 00 00 00 10 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 01 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [060] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [070] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [080] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [090] 00 00 00 00 00 00 00 00                           ........ 
[2002/05/13 15:40:24, 5] smbd/nttrans.c:call_nt_transact_create(1156)
  call_nt_transact_create
[2002/05/13 15:40:24, 10] smbd/nttrans.c:map_create_disposition(401)
  map_create_disposition: Mapped create_disposition 5 to 12
[2002/05/13 15:40:24, 10] smbd/nttrans.c:get_filename_transact(290)
  get_filename_transact: data_offset = 53, data_len = 1, fname_len = 36
[2002/05/13 15:40:24, 10] smbd/nttrans.c:map_share_mode(500)
  map_share_mode: DELETE_ACCESS requested. open_mode = 20001
[2002/05/13 15:40:24, 10] smbd/nttrans.c:map_share_mode(528)
  map_share_mode: Mapped desired access 130196, share access 3, file
attributes 80 to open_mode 20041
[2002/05/13 15:40:24, 5] smbd/filename.c:unix_convert(146)
  unix_convert called on file "\Lab5Circuits.html"
[2002/05/13 15:40:24, 3] lib/util.c:unix_clean_name(391)
  unix_clean_name [/Lab5Circuits.html]
[2002/05/13 15:40:24, 5] smbd/mangle.c:is_8_3(338)
  Checking Lab5Circuits.html for 8.3
[2002/05/13 15:40:24, 3] smbd/dosmode.c:unix_mode(113)
  unix_mode(Lab5Circuits.html) returning 0744
[2002/05/13 15:40:24, 5] smbd/files.c:file_new(107)
  allocated file structure 6273, fnum = 10369 (1 used)
[2002/05/13 15:40:24, 10] smbd/open.c:open_file_shared(656)
  open_file_shared: fname = Lab5Circuits.html, share_mode = 20041, ofun =
12, mode = 744, oplock request = 0
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 3] lib/util.c:unix_clean_name(391)
  unix_clean_name [Lab5Circuits.html]
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(125)
  dos_mode: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(169)
  dos_mode returning a
[2002/05/13 15:40:24, 4] smbd/open.c:open_file_shared(785)
  calling open_file with flags=0x1 flags2=0x240 mode=0744
[2002/05/13 15:40:24, 10] smbd/open.c:fd_open(53)
  fd_open: name Lab5Circuits.html, flags = 04101 mode = 0744, fd = 19. 
[2002/05/13 15:40:24, 2] smbd/open.c:open_file(217)
  dcurrie opened file Lab5Circuits.html read=No write=Yes (numopen=1)
[2002/05/13 15:40:24, 10] smbd/open.c:open_file_shared(886)
  open_file_shared : share_mode = 20041
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(125)
  dos_mode: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(169)
  dos_mode returning a
[2002/05/13 15:40:24, 10] locking/locking.c:del_share_entry(499)
  del_share_mode: num_share_modes = 1
[2002/05/13 15:40:24, 10] locking/locking.c:del_share_entry(510)
  del_share_mode: deleting entry 0
[2002/05/13 15:40:24, 10] smbd/close.c:close_normal_file(157)
  close_normal_file: share_entry_count = 0 for file Lab5Circuits.html
[2002/05/13 15:40:24, 2] smbd/close.c:close_normal_file(208)
  dcurrie closed file Lab5Circuits.html (numopen=0) 
[2002/05/13 15:40:24, 5] smbd/files.c:file_free(306)
  freed files structure 10369 (0 used)
[2002/05/13 15:40:24, 3] smbd/error.c:error_packet(99)
  error string = Operation not permitted
[2002/05/13 15:40:24, 3] smbd/error.c:error_packet(112)
  error packet at smbd/nttrans.c(1405) cmd=160 (SMBnttrans) eclass=1
ecode=12
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=35
  smb_com=0xa0
  smb_rcls=1
  smb_reh=0
  smb_err=12
  smb_flg=136
  smb_flg2=65
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20736
  smt_wct=0
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=0
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(560)
  write_socket(12,39)
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(563)
  write_socket(12,39) wrote 39
[2002/05/13 15:40:24, 10]
lib/util_sock.c:read_smb_length_return_keepalive(602)
  got smb length of 320
[2002/05/13 15:40:24, 6] smbd/process.c:process_smb(859)
  got message type 0x0 of len 0x140
[2002/05/13 15:40:24, 3] smbd/process.c:process_smb(860)
  Transaction 37 of length 324
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=320
  smb_com=0xa0
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=2055
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20800
  smt_wct=19
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[0]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[1]=23040 (0x5A00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[2]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[3]=38912 (0x9800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[4]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[5]=25856 (0x6500)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[6]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[7]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[8]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[9]=23040 (0x5A00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[10]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[11]=19456 (0x4C00)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[12]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[13]=38912 (0x9800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[14]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[15]=43008 (0xA800)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[16]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[17]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[18]=1 (0x1)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=247
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 68 00 10 00 00 00 00  00 00 00 96 01 12 00 00  .h...... ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 00 00 00 00 00 00 00 80  00 00 00 03 00 00 00 05  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 00 00 00 44 00 00 00 00  00 00 00 98 00 00 00 24  ...D.... .......$
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 00 00 00 02 00 00 00 03  00 5C 00 4C 00 61 00 62  ........ .\.L.a.b
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 00 35 00 43 00 69 00 72  00 63 00 75 00 69 00 74  .5.C.i.r .c.u.i.t
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 00 73 00 2E 00 68 00 74  00 6D 00 6C 00 00 00 00  .s...h.t .m.l....
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [060] 00 00 00 80 0F 80 00 4E  65 74 41 27 73 20 41 56  .......N etA's AV
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [070] 20 53 74 61 6D 70 00 01  00 00 00 BD EB 00 00 08   Stamp.. ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [080] 00 00 00 04 00 00 00 00  00 00 00 10 00 00 00 01  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [090] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0A0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0B0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0C0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0D0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0E0] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [0F0] 00 00 00 00 00 00 00                              ....... 
[2002/05/13 15:40:24, 3] smbd/process.c:switch_message(667)
  switch message SMBnttrans (pid 15241)
[2002/05/13 15:40:24, 4] smbd/uid.c:become_user(119)
  Skipping become_user - already user
[2002/05/13 15:40:24, 10] smbd/nttrans.c:reply_nttrans(1858)
  reply_nttrans: parameter_count = 90
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 10 00 00 00 00 00 00 00  96 01 12 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 00 00 00 00 80 00 00 00  03 00 00 00 05 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 44 00 00 00 00 00 00 00  98 00 00 00 24 00 00 00  D....... ....$...
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 02 00 00 00 03 00 5C 00  4C 00 61 00 62 00 35 00  ......\. L.a.b.5.
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 43 00 69 00 72 00 63 00  75 00 69 00 74 00 73 00  C.i.r.c. u.i.t.s.
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 2E 00 68 00 74 00 6D 00  6C 00                    ..h.t.m. l.
[2002/05/13 15:40:24, 10] smbd/nttrans.c:reply_nttrans(1863)
  reply_nttrans: data_count = 152
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 00 00 00 80 0F 80 00  4E 65 74 41 27 73 20 41  ........ NetA's A
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [010] 56 20 53 74 61 6D 70 00  01 00 00 00 BD EB 00 00  V Stamp. ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [020] 08 00 00 00 04 00 00 00  00 00 00 00 10 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [030] 01 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [040] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [050] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [060] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [070] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [080] 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00  ........ ........
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1443)
  [090] 00 00 00 00 00 00 00 00                           ........ 
[2002/05/13 15:40:24, 5] smbd/nttrans.c:call_nt_transact_create(1156)
  call_nt_transact_create
[2002/05/13 15:40:24, 10] smbd/nttrans.c:map_create_disposition(401)
  map_create_disposition: Mapped create_disposition 5 to 12
[2002/05/13 15:40:24, 10] smbd/nttrans.c:get_filename_transact(290)
  get_filename_transact: data_offset = 53, data_len = 1, fname_len = 36
[2002/05/13 15:40:24, 10] smbd/nttrans.c:map_share_mode(528)
  map_share_mode: Mapped desired access 120196, share access 3, file
attributes 80 to open_mode 41
[2002/05/13 15:40:24, 5] smbd/filename.c:unix_convert(146)
  unix_convert called on file "\Lab5Circuits.html"
[2002/05/13 15:40:24, 3] lib/util.c:unix_clean_name(391)
  unix_clean_name [/Lab5Circuits.html]
[2002/05/13 15:40:24, 5] smbd/mangle.c:is_8_3(338)
  Checking Lab5Circuits.html for 8.3
[2002/05/13 15:40:24, 3] smbd/dosmode.c:unix_mode(113)
  unix_mode(Lab5Circuits.html) returning 0744
[2002/05/13 15:40:24, 5] smbd/files.c:file_new(107)
  allocated file structure 6274, fnum = 10370 (1 used)
[2002/05/13 15:40:24, 10] smbd/open.c:open_file_shared(656)
  open_file_shared: fname = Lab5Circuits.html, share_mode = 41, ofun = 12,
mode = 744, oplock request = 0
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 3] lib/util.c:unix_clean_name(391)
  unix_clean_name [Lab5Circuits.html]
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(125)
  dos_mode: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(169)
  dos_mode returning a
[2002/05/13 15:40:24, 4] smbd/open.c:open_file_shared(785)
  calling open_file with flags=0x1 flags2=0x240 mode=0744
[2002/05/13 15:40:24, 10] smbd/open.c:fd_open(53)
  fd_open: name Lab5Circuits.html, flags = 04101 mode = 0744, fd = 19. 
[2002/05/13 15:40:24, 2] smbd/open.c:open_file(217)
  dcurrie opened file Lab5Circuits.html read=No write=Yes (numopen=1)
[2002/05/13 15:40:24, 10] smbd/open.c:open_file_shared(886)
  open_file_shared : share_mode = 41
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(125)
  dos_mode: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1106)
  is_in_path: Lab5Circuits.html
[2002/05/13 15:40:24, 8] lib/util.c:is_in_path(1111)
  is_in_path: no name list.
[2002/05/13 15:40:24, 8] smbd/dosmode.c:dos_mode(169)
  dos_mode returning a
[2002/05/13 15:40:24, 10] locking/locking.c:del_share_entry(499)
  del_share_mode: num_share_modes = 1
[2002/05/13 15:40:24, 10] locking/locking.c:del_share_entry(510)
  del_share_mode: deleting entry 0
[2002/05/13 15:40:24, 10] smbd/close.c:close_normal_file(157)
  close_normal_file: share_entry_count = 0 for file Lab5Circuits.html
[2002/05/13 15:40:24, 2] smbd/close.c:close_normal_file(208)
  dcurrie closed file Lab5Circuits.html (numopen=0) 
[2002/05/13 15:40:24, 5] smbd/files.c:file_free(306)
  freed files structure 10370 (0 used)
[2002/05/13 15:40:24, 3] smbd/error.c:error_packet(99)
  error string = Operation not permitted
[2002/05/13 15:40:24, 3] smbd/error.c:error_packet(112)
  error packet at smbd/nttrans.c(1405) cmd=160 (SMBnttrans) eclass=1
ecode=12
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=35
  smb_com=0xa0
  smb_rcls=1
  smb_reh=0
  smb_err=12
  smb_flg=136
  smb_flg2=65
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20800
  smt_wct=0
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=0
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(560)
  write_socket(12,39)
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(563)
  write_socket(12,39) wrote 39
[2002/05/13 15:40:24, 10]
lib/util_sock.c:read_smb_length_return_keepalive(602)
  got smb length of 70
[2002/05/13 15:40:24, 6] smbd/process.c:process_smb(859)
  got message type 0x0 of len 0x46
[2002/05/13 15:40:24, 3] smbd/process.c:process_smb(860)
  Transaction 38 of length 74
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=70
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=2055
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20864
  smt_wct=15
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[0]=2 (0x2)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[1]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[2]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[3]=560 (0x230)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[4]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[5]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[6]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[7]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[8]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[9]=2 (0x2)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[10]=68 (0x44)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[11]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[12]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[13]=1 (0x1)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[14]=3 (0x3)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=5
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 5C 00 02 01                                    .\... 
[2002/05/13 15:40:24, 3] smbd/process.c:switch_message(667)
  switch message SMBtrans2 (pid 15241)
[2002/05/13 15:40:24, 4] smbd/uid.c:become_user(119)
  Skipping become_user - already user
[2002/05/13 15:40:24, 3] smbd/trans2.c:call_trans2qfsinfo(1129)
  call_trans2qfsinfo: level = 258
[2002/05/13 15:40:24, 5] smbd/trans2.c:call_trans2qfsinfo(1226)
  call_trans2qfsinfo : SMB_QUERY_FS_VOLUME_INFO namelen = 7, vol = dcurrie
[2002/05/13 15:40:24, 9] smbd/trans2.c:send_trans2_replies(159)
  t2_rep: params_sent_thistime = 0, data_sent_thistime = 32, useable_space =
65475
[2002/05/13 15:40:24, 9] smbd/trans2.c:send_trans2_replies(161)
  t2_rep: params_to_send = 0, data_to_send = 32, paramsize = 0, datasize =
32
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(560)
  write_socket(12,92)
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(563)
  write_socket(12,92) wrote 92
[2002/05/13 15:40:24, 4] smbd/trans2.c:call_trans2qfsinfo(1263)
  SMBtrans2 info_level = 258
[2002/05/13 15:40:24, 10]
lib/util_sock.c:read_smb_length_return_keepalive(602)
  got smb length of 70
[2002/05/13 15:40:24, 6] smbd/process.c:process_smb(859)
  got message type 0x0 of len 0x46
[2002/05/13 15:40:24, 3] smbd/process.c:process_smb(860)
  Transaction 39 of length 74
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(280)
  size=70
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=2055
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(286)
  smb_tid=1
  smb_pid=964
  smb_uid=100
  smb_mid=20928
  smt_wct=15
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[0]=2 (0x2)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[1]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[2]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[3]=560 (0x230)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[4]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[5]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[6]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[7]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[8]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[9]=2 (0x2)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[10]=68 (0x44)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[11]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[12]=0 (0x0)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[13]=1 (0x1)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(291)
  smb_vwv[14]=3 (0x3)
[2002/05/13 15:40:24, 5] lib/util.c:show_msg(296)
  smb_bcc=5
[2002/05/13 15:40:24, 10] lib/util.c:dump_data(1435)
  [000] 00 5C 00 05 01                                    .\... 
[2002/05/13 15:40:24, 3] smbd/process.c:switch_message(667)
  switch message SMBtrans2 (pid 15241)
[2002/05/13 15:40:24, 4] smbd/uid.c:become_user(119)
  Skipping become_user - already user
[2002/05/13 15:40:24, 3] smbd/trans2.c:call_trans2qfsinfo(1129)
  call_trans2qfsinfo: level = 261
[2002/05/13 15:40:24, 9] smbd/trans2.c:send_trans2_replies(159)
  t2_rep: params_sent_thistime = 0, data_sent_thistime = 20, useable_space =
65475
[2002/05/13 15:40:24, 9] smbd/trans2.c:send_trans2_replies(161)
  t2_rep: params_to_send = 0, data_to_send = 20, paramsize = 0, datasize =
20
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(560)
  write_socket(12,80)
[2002/05/13 15:40:24, 6] lib/util_sock.c:write_socket(563)
  write_socket(12,80) wrote 80
[2002/05/13 15:40:24, 4] smbd/trans2.c:call_trans2qfsinfo(1263)
  SMBtrans2 info_level = 261