Winreg problem testing Ricoh drivers on 3.6.0rc2

Wed Jul 13 08:20:19 MDT 2011

Hi Guys,

We recently received a Ricoh Aficio MP C3001 workgroup printer for
testing here. I have been doing testing of this printer on Samba
3.6.0rc2 and have run into a hang using its PCL6 driver. Basically after
installing the driver, then adding the printer to a remote system and
trying the bring up the properties on that remote system the printer
dialog screen freezes for quite some time (can be minutes) then finally
comes up, but missing all of the Ricoh specific properties. While the
freeze is happening the Samba log file associated with the remote IP of
the workstation grows quite a bit (1-2MB on log level 3) filled with
entries like the attached text file. The log shows the driver trying to
access registry keys that exists on Windows when the driver is installed
locally.

Thoughts? Apologies if this should have instead gone to the users list.
I'll move it there if so.

Thanks,

Justin
-------------- next part --------------
[2011/07/13 09:06:50.911481,  2] rpc_server/spoolss/srv_spoolss_util.c:960(winreg_create_printer)
  winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\richo_tester already exists
[2011/07/13 09:06:50.911625,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2011/07/13 09:06:50.911720,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2011/07/13 09:06:50.911870,  3] rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2011/07/13 09:06:51.068601,  3] smbd/process.c:1661(process_smb)
  Transaction 28130 of length 132 (0 toread)
[2011/07/13 09:06:51.068695,  3] smbd/process.c:1466(switch_message)
  switch message SMBtrans (pid 21698) conn 0x7fbacccd5950
[2011/07/13 09:06:51.068728,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=44 params=0 setup=2
[2011/07/13 09:06:51.068764,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2011/07/13 09:06:51.068784,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 3820)
[2011/07/13 09:06:51.068822,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2011/07/13 09:06:51.068845,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2011/07/13 09:06:51.068883,  3] rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2011/07/13 09:06:51.361057,  3] smbd/process.c:1661(process_smb)
  Transaction 28131 of length 132 (0 toread)
[2011/07/13 09:06:51.361166,  3] smbd/process.c:1466(switch_message)
  switch message SMBtrans (pid 21698) conn 0x7fbacccd5950
[2011/07/13 09:06:51.361200,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=44 params=0 setup=2
[2011/07/13 09:06:51.361225,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2011/07/13 09:06:51.361247,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 3820)
[2011/07/13 09:06:51.361285,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2011/07/13 09:06:51.361309,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2011/07/13 09:06:51.361352,  3] rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2011/07/13 09:06:51.366619,  3] smbd/process.c:1661(process_smb)
  Transaction 28132 of length 260 (0 toread)
[2011/07/13 09:06:51.366708,  3] smbd/process.c:1466(switch_message)
  switch message SMBtrans (pid 21698) conn 0x7fbacccd5950
[2011/07/13 09:06:51.366742,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=172 params=0 setup=2
[2011/07/13 09:06:51.366776,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2011/07/13 09:06:51.366798,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 3820)
[2011/07/13 09:06:51.366836,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
[2011/07/13 09:06:51.367196,  2] rpc_server/spoolss/srv_spoolss_util.c:2353(winreg_get_printer_dataex)
  winreg_get_printer_dataex: Could not open key PrinterDriverData\capsdb_scope: WERR_BADFILE
[2011/07/13 09:06:51.367249,  3] rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2011/07/13 09:06:51.451048,  3] smbd/process.c:1661(process_smb)
  Transaction 28133 of length 248 (0 toread)
[2011/07/13 09:06:51.451133,  3] smbd/process.c:1466(switch_message)
  switch message SMBtrans (pid 21698) conn 0x7fbacccd5950
[2011/07/13 09:06:51.451163,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=160 params=0 setup=2
[2011/07/13 09:06:51.451185,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2011/07/13 09:06:51.451206,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 3820)
[2011/07/13 09:06:51.451259,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
[2011/07/13 09:06:51.451616,  2] rpc_server/spoolss/srv_spoolss_util.c:2353(winreg_get_printer_dataex)
  winreg_get_printer_dataex: Could not open key PrinterDriverData\ui_scope: WERR_BADFILE
[2011/07/13 09:06:51.451659,  3] rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2011/07/13 09:06:51.455412,  3] smbd/process.c:1661(process_smb)
  Transaction 28134 of length 540 (0 toread)
[2011/07/13 09:06:51.455498,  3] smbd/process.c:1466(switch_message)
  switch message SMBtrans (pid 21698) conn 0x7fbacccd5950
[2011/07/13 09:06:51.455528,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=452 params=0 setup=2
[2011/07/13 09:06:51.455550,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2011/07/13 09:06:51.455570,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 3820)
[2011/07/13 09:06:51.455605,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\city-gentoo\richo_tester
[2011/07/13 09:06:51.455649,  3] rpc_server/spoolss/srv_spoolss_nt.c:519(set_printer_hnd_printertype)
  Setting printer type=\\city-gentoo\richo_tester
[2011/07/13 09:06:51.455718,  3] lib/access.c:338(allow_access)
  Allowed connection from bynrf11.city.kawarthalakes.on.ca (10.50.4.216)
[2011/07/13 09:06:51.457121,  2] rpc_server/spoolss/srv_spoolss_util.c:960(winreg_create_printer)
  winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\richo_tester already exists
[2011/07/13 09:06:51.457183,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2011/07/13 09:06:51.457210,  3] rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy