[Samba] Can't print duplex -- getting worse

Thomas Bork tombork at web.de
Mon May 27 14:23:02 GMT 2002


Hi @all, hallo Gerald,

Gerald Carter wrote:

> Please apply the printing patch roll up in
>
>    ftp://ftp.samba.org/pub/jerry/samba-2.2.4-patches/
>
> and report back.  There were a few significant bugs fixed here.
> Sorry for the inconvience.  I'm hoping to get 2.2.5 out within the
> next week or so.

at first excuse my bad english please.

The patch doesn't work for me. Printing as an equal user to root named
eis from w2k failed (word-document on local machine).
After applying the patch printer properties for admin users are
displayed but i cannot print  :o(
But test pages from w2k I can print. In 2.2.3a everything worked fine.
Because version 2.2.5 maybe following next week, I'm posting a level 3
log here (sorry, if it is too long):

If printing failes:


[2002/05/28 00:49:23, 3] smbd/process.c:process_smb(866)
  Transaction 57 of length 1068
[2002/05/28 00:49:23, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 00:49:23, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=988 params=0 setup=2
[2002/05/28 00:49:23, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 00:49:23, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 751d)free_pipe_context:
destroying talloc pool of size 0
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29981 rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\linux\lp0
[2002/05/28 00:49:23, 3]
rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(379)
  Setting printer type=\\linux\lp0
[2002/05/28 00:49:23, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(446)
  free_pipe_context: destroying talloc pool of size 868
[2002/05/28 00:49:23, 3] smbd/process.c:process_smb(866)
  Transaction 58 of length 1304
[2002/05/28 00:49:23, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 00:49:23, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=1224 params=0 setup=2
[2002/05/28 00:49:23, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 00:49:23, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 751d)free_pipe_context:
destroying talloc pool of size 0
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29981 rpc command: SPOOLSS_GETPRINTER
[2002/05/28 00:49:23, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(446)
  free_pipe_context: destroying talloc pool of size 1512
[2002/05/28 00:49:23, 3] smbd/process.c:process_smb(866)
  Transaction 59 of length 124
[2002/05/28 00:49:23, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 00:49:23, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 00:49:23, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=44 params=0 setup=2
[2002/05/28 00:49:23, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 00:49:23, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 751d)free_pipe_context:
destroying talloc pool of size 0
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 00:49:23, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29981 rpc command: SPOOLSS_CLOSEPRINTER
[2002/05/28 00:49:23, 3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(197)
  Closed policy
[2002/05/28 00:49:23, 3]
rpc_server/srv_pipe_hnd.c:free_pipe_context(446)
  free_pipe_context: destroying talloc pool of size 0



A log with the test page, which I can print:


[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=4148 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 4096
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 516 of length 4228
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=4148 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 4096
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 517 of length 4228
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=4148 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 4096
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 518 of length 4228
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=4148 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 4096
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 519 of length 2172
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=2092 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 2037
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 520 of length 124
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=44 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_ENDPAGEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 0
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 521 of length 144
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=64 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_WRITEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 11
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 522 of length 124
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=44 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_ENDDOCPRINTER
[2002/05/28 01:04:58, 3] printing/print_generic.c:print_run_command(8
  Running the command `chmod 666 smbprn.000004.Qdm9Fv; /usr/bin/lpr -
Qdm9Fv; rm smbprn.000004.Qdm9Fv' gave 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 0
[2002/05/28 01:04:58, 3] smbd/process.c:process_smb(866)
  Transaction 523 of length 124
[2002/05/28 01:04:58, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:04:58, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:04:58, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=44 params=0 setup=2
[2002/05/28 01:04:58, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:04:58, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_CLOSEPRINTER
[2002/05/28 01:04:58, 3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(19
  Closed policy
[2002/05/28 01:04:58, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 0
[2002/05/28 01:05:01, 3] smbd/process.c:process_smb(866)
  Transaction 524 of length 124
[2002/05/28 01:05:01, 3] smbd/process.c:switch_message(673)
  switch message SMBtrans (pid 2797)
[2002/05/28 01:05:01, 3] smbd/sec_ctx.c:set_sec_ctx(314)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/05/28 01:05:01, 3] smbd/sec_ctx.c:set_sec_ctx(319)
  1 user groups:
  0
[2002/05/28 01:05:01, 3] smbd/ipc.c:reply_trans(480)
  trans <\PIPE\> data=44 params=0 setup=2
[2002/05/28 01:05:01, 3] smbd/ipc.c:named_pipe(334)
  named pipe command on <> name
[2002/05/28 01:05:01, 3] smbd/ipc.c:api_fd_reply(296)
  Got API command 0x26 on pipe "spoolss" (pnum 7522)free_pipe_context
e 0
[2002/05/28 01:05:01, 3] rpc_server/srv_pipe.c:api_pipe_request(1148)
  Doing \PIPE\spoolss
[2002/05/28 01:05:01, 3] rpc_server/srv_pipe.c:api_rpcTNP(1180)
  api_rpcTNP: pipe 29986 rpc command: SPOOLSS_CLOSEPRINTER
[2002/05/28 01:05:01, 3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(19
  Closed policy
[2002/05/28 01:05:01, 3] rpc_server/srv_pipe_hnd.c:free_pipe_context(
  free_pipe_context: destroying talloc pool of size 0


At last:
Does anybody know, why my printer (HP-Laserjet 4 Plus) everytime ask's
me for loading Letter format? I'm using format A4 (also showing in my
printer properties).

Thanks, In Advance.
Thomas Bork





More information about the samba mailing list