[Samba] Samba PDC problem: Please help me avoid a mutiny! :-)

Sun Nov 10 06:47:00 GMT 2002

Thank you very much for your reply!

On Saturday 09 November 2002 21:14, Andrew Bartlett wrote:
>> ..
> > The manifestation is, in nearly all cases, that the PDC sends a message
> > to the workstation and waits for a response.
>
> This protocol doesn't usually work like that.  SMB is (for all cases
> except oplocks) a client-initiated protocol - the client is always
> waiting for the server.
>
> What message/reply do you think the server is waiting for?
>
> Andrew Bartlett

I confess that I'm not really up on the SMB/CIFS protocols (though I am learning a lot lately).
Here's a fragment from a log which might help. I am not sure how to identify what the message to the WS is
or what the WS is expected to do in response. I'm turning off wordwrap for readabillity.

If I get this right, this is a message being sent to the WS, after which the PDC looks for a reply. After waiting
for about 50 seconds, Samba gets an EOF back from the WS and does timeout processing (the third
entry from the bottom in the log fragment I quote here). The line " receive_smb: length < 0!" is a little misleading:
read_smb_length_return_keepalive returns
-1 whenever it fails to read exactly 4 bytes from the socket, and that's what the message in the log
is reporting.

[2002/10/30 23:52:08.534309, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(275)
  size=119
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=1
[2002/10/30 23:52:08.534414, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(281)
  smb_tid=2
  smb_pid=27104
  smb_uid=103
  smb_mid=43778
  smt_wct=42
[2002/10/30 23:52:08.534500, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[0]=255 (0xFF)
[2002/10/30 23:52:08.534558, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[1]=0 (0x0)
[2002/10/30 23:52:08.534614, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[2]=3074 (0xC02)
[2002/10/30 23:52:08.534671, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[3]=277 (0x115)
[2002/10/30 23:52:08.534728, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[4]=128 (0x80)
[2002/10/30 23:52:08.534784, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[5]=0 (0x0)
[2002/10/30 23:52:08.534840, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[6]=5216 (0x1460)
[2002/10/30 23:52:08.534898, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[7]=62376 (0xF3A8)
[2002/10/30 23:52:08.534954, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[8]=49752 (0xC258)
[2002/10/30 23:52:08.535276, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[9]=1 (0x1)
[2002/10/30 23:52:08.535369, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[10]=60110 (0xEACE)
[2002/10/30 23:52:08.535428, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[11]=41307 (0xA15B)
[2002/10/30 23:52:08.535486, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[12]=49792 (0xC280)
[2002/10/30 23:52:08.535617, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[13]=1 (0x1)
[2002/10/30 23:52:08.535682, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[14]=5216 (0x1460)
[2002/10/30 23:52:08.535739, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[15]=62376 (0xF3A8)
[2002/10/30 23:52:08.535796, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[16]=49752 (0xC258)
[2002/10/30 23:52:08.535853, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[17]=1 (0x1)
[2002/10/30 23:52:08.535909, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[18]=5216 (0x1460)
[2002/10/30 23:52:08.535966, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[19]=62376 (0xF3A8)
[2002/10/30 23:52:08.536023, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[20]=49752 (0xC258)
[2002/10/30 23:52:08.536083, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[21]=8193 (0x2001)
[2002/10/30 23:52:08.536142, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[22]=0 (0x0)
[2002/10/30 23:52:08.536199, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[23]=48640 (0xBE00)
[2002/10/30 23:52:08.536256, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[24]=1 (0x1)
[2002/10/30 23:52:08.536315, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[25]=0 (0x0)
[2002/10/30 23:52:08.536371, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[26]=0 (0x0)
[2002/10/30 23:52:08.536428, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[27]=48640 (0xBE00)
[2002/10/30 23:52:08.536486, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[28]=1 (0x1)
[2002/10/30 23:52:08.536542, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[29]=0 (0x0)
[2002/10/30 23:52:08.536602, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[30]=0 (0x0)
[2002/10/30 23:52:08.536658, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[31]=0 (0x0)
[2002/10/30 23:52:08.536715, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[32]=0 (0x0)
[2002/10/30 23:52:08.536772, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[33]=0 (0x0)
[2002/10/30 23:52:08.536828, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[34]=0 (0x0)
[2002/10/30 23:52:08.536884, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[35]=0 (0x0)
[2002/10/30 23:52:08.536941, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[36]=0 (0x0)
[2002/10/30 23:52:08.536997, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[37]=0 (0x0)
[2002/10/30 23:52:08.537054, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[38]=0 (0x0)
[2002/10/30 23:52:08.537111, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[39]=0 (0x0)
[2002/10/30 23:52:08.537167, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[40]=0 (0x0)
[2002/10/30 23:52:08.537224, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(286)
  smb_vwv[41]=0 (0x0)
[2002/10/30 23:52:08.537281, 5, pid=15553, effective(13080, 100), real(0, 0)] lib/util.c:show_msg(291)
  smb_bcc=0
[2002/10/30 23:52:08.537343, 6, pid=15553, effective(13080, 100), real(0, 0)] lib/util_sock.c:write_socket(518)
  write_socket(5,123)
[2002/10/30 23:52:08.537720, 6, pid=15553, effective(13080, 100), real(0, 0)] lib/util_sock.c:write_socket(521)
  write_socket(5,123) wrote 123
[2002/10/30 23:52:57.782646, 10, pid=15553, effective(13080, 100), real(0, 0)] lib/util_sock.c:read_data(430)
  read_data: read of 4 returned 0. Error = Success
[2002/10/30 23:52:57.783005, 10, pid=15553, effective(13080, 100), real(0, 0)] lib/util_sock.c:receive_smb(611)
  receive_smb: length < 0!
[2002/10/30 23:52:57.783086, 3, pid=15553, effective(13080, 100), real(0, 0)] smbd/process.c:timeout_processing(1092)
  receive_smb error (Success) exiting