[Samba] Unable to print to SAMBA+CUPS server from Win2k12 server

Michael Mol mikemol at gmail.com
Tue Feb 4 09:49:21 MST 2014


I'm stumped! From within CUPS, I'm able to successfully print test
pages.

From the Windows machine, I'm able to:
* Browse the Samba server's printers via SMB
* Add the Samba server's printers
* Write to the Samba server's print$ share

If I pause the printers via CUPS's interface, then Samba successfully
relays this information when I try printing a test page from the
Windows machine.

If I do not pause (or if I unpause) the printers from CUPS's interface,
then Windows reports no error printing a test page, yet the job does
not show up in the print queue. I *think* the problem is somewhwere in
my samba setup. But, like I said, I'm stumped!

Worth noting in the logs is the occasional appearance of
NT_STATUS_ACCESS_DENIED...but Windows doesn't present that to me. I
don't know what's being denied access to or how, and there's also
nothing showing up under /var/log/audit/audit.log--so I know it's not
rooted in SELinux.

Windows server is running Server 2k12.
printer server is running CentOS 6.5, samba  3.6.9-167.el6_5, and cups
1:1.4.2-50.el6_4.5

smb.conf, stripped of comments and identifying information:

[global]
   workgroup = WINDOWS
   password server = 10.161.1.32
   realm = WINDOWS.EXAMPLE.COM
   security = ads
   idmap config * : backend = autorid
   idmap config * : range = 16777216-33554431
   template shell = /bin/false
   winbind use default domain = yes
   winbind offline logon = true
windbind uid = 16777216-33554431
winbind gid = 16777216-33554431
windbind use default domain = yes
interfaces = eth0 lo
winbind refresh tickets = yes
template homedir = /home/%D/%U
template shell = /bin/bash
invalid users = root
        server string = Samba Server Version %v
        log file = /var/log/samba/log.%m
        max log size = 50
        log level = 3
        passdb backend = tdbsam
preferred master = no
wins server = 10.161.1.32
dns proxy = no
        load printers = yes
        cups options = raw
        printing = cups
        printcap name = cups
[printers]
        comment = All Printers
        path = /var/spool/samba
        browseable = no
        guest ok = Yes
        writable = no
        printable = yes
        printer admin = root, administrator
[print$]
        comment = Printer Drivers
        path = /etc/samba/drivers
        browseable = yes
        guest ok = no
        read only = yes
        write list = root, administrator

Logs collected using this command: 
cd /var/log ; tail -n0 -f {samba,cups}/*

Ideally, that will help correlate log entries with each other. For a
500ms event at log level 3...it's a lot of logs, and makes up the
remainder of the email:

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.186759,  3] smbd/process.c:1662(process_smb)
  Transaction 911 of length 106 (0 toread)
[2014/02/04 09:53:55.186956,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.187660,  3] smbd/process.c:1662(process_smb)
  Transaction 912 of length 88 (0 toread)
[2014/02/04 09:53:55.187782,  3] smbd/process.c:1467(switch_message)
  switch message SMBnttrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.187855,  3] smbd/error.c:81(error_packet_set)
  error packet at smbd/nttrans.c(3118) cmd=160 (SMBnttrans)
NT_STATUS_ACCESS_DENIED
[2014/02/04 09:53:55.188470,  3] smbd/process.c:1662(process_smb)
  Transaction 913 of length 228 (0 toread)
[2014/02/04 09:53:55.188592,  3] smbd/process.c:1467(switch_message)
  switch message SMBwriteX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.188688,  3]
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/02/04 09:53:55.188757,  3]
rpc_server/srv_pipe.c:339(check_bind_req)
  check_bind_req for \spoolss
[2014/02/04 09:53:55.188821,  3]
rpc_server/srv_pipe.c:346(check_bind_req)
  check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/02/04 09:53:55.188924,  3] smbd/pipes.c:361(pipe_write_andx_done)
  writeX-IPC nwritten=160
[2014/02/04 09:53:55.189545,  3] smbd/process.c:1662(process_smb)
  Transaction 914 of length 63 (0 toread)
[2014/02/04 09:53:55.189715,  3] smbd/process.c:1467(switch_message)
  switch message SMBreadX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.189837,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 29
[2014/02/04 09:53:55.189963,  3] smbd/pipes.c:485(pipe_read_andx_done)
  readX-IPC min=1024 max=1024 nread=68
[2014/02/04 09:53:55.190548,  3] smbd/process.c:1662(process_smb)
  Transaction 915 of length 338 (0 toread)
[2014/02/04 09:53:55.190717,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.190841,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=250 params=0 setup=2
[2014/02/04 09:53:55.190954,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.191058,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387c)
[2014/02/04 09:53:55.191192,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\printer-server\Savin-Main
[2014/02/04 09:53:55.191375,  3]
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
  Setting printer type=\\printer-server\Savin-Main
[2014/02/04 09:53:55.191675,  3] lib/access.c:338(allow_access)
  Allowed connection from 10.161.1.32 (10.161.1.32)

==> samba/log.winbindd <==
[2014/02/04 09:53:55.191959,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.193359,
3] ../libcli/security/dom_sid.c:208(dom_sid_parse_endp)
  string_to_sid: SID root is not in a valid format

==> samba/log.winbindd <==
[2014/02/04 09:53:55.193764,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.194001,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.194193,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.194410,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.195398,  2]
rpc_client/cli_winreg_spoolss.c:896(winreg_create_printer)
  winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows
NT\CurrentVersion\Print\Printers\Savin-Main already exists
[2014/02/04 09:53:55.195596,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.195737,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.195911,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 1679
[2014/02/04 09:53:55.197458,  3] smbd/process.c:1662(process_smb)
  Transaction 916 of length 4244 (0 toread)
[2014/02/04 09:53:55.197644,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.197769,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.197887,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.198009,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387c)
[2014/02/04 09:53:55.198141,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.199765,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.199933,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.200073,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.200209,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> cups/error_log <==
D [04/Feb/2014:09:53:55 -0600] cupsdAcceptClient: skipping getpeercon()
D [04/Feb/2014:09:53:55 -0600] cupsdAcceptClient: 13 from localhost
(Domain)
D [04/Feb/2014:09:53:55 -0600] Report: clients=1
D [04/Feb/2014:09:53:55 -0600] Report: jobs=8
D [04/Feb/2014:09:53:55 -0600] Report: jobs-active=0
D [04/Feb/2014:09:53:55 -0600] Report: printers=3
D [04/Feb/2014:09:53:55 -0600] Report: printers-implicit=0
D [04/Feb/2014:09:53:55 -0600] Report: stringpool-string-count=551
D [04/Feb/2014:09:53:55 -0600] Report: stringpool-alloc-bytes=8416
D [04/Feb/2014:09:53:55 -0600] Report: stringpool-total-bytes=12008
D [04/Feb/2014:09:53:55 -0600] cupsdReadClient: 13 POST / HTTP/1.1
D [04/Feb/2014:09:53:55 -0600] cupsdSetBusyState: Active clients
D [04/Feb/2014:09:53:55 -0600] cupsdAuthorize: No authentication data
provided.
D [04/Feb/2014:09:53:55 -0600] cupsdReadClient: 13 1.1 Get-Jobs 1
D [04/Feb/2014:09:53:55 -0600] Get-Jobs
ipp://localhost/printers/Savin-Main
D [04/Feb/2014:09:53:55 -0600] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/Savin-Main) from localhost
D [04/Feb/2014:09:53:55 -0600] cupsdSetBusyState: Not busy
D [04/Feb/2014:09:53:55 -0600] cupsdReadClient: 13 POST / HTTP/1.1
D [04/Feb/2014:09:53:55 -0600] cupsdSetBusyState: Active clients
D [04/Feb/2014:09:53:55 -0600] cupsdAuthorize: No authentication data
provided.
D [04/Feb/2014:09:53:55 -0600] cupsdReadClient: 13 1.1
Get-Printer-Attributes 1
D [04/Feb/2014:09:53:55 -0600] Get-Printer-Attributes
ipp://localhost/printers/Savin-Main
D [04/Feb/2014:09:53:55 -0600] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost/printers/Savin-Main) from
localhost
D [04/Feb/2014:09:53:55 -0600] cupsdSetBusyState: Not busy

==> samba/log.smbd <==
[2014/02/04 09:53:55.204589,  3]
printing/printing.c:1390(print_queue_update_internal)
  print_queue_update_internal: 0 jobs in queue for Savin-Main

==> cups/error_log <==
D [04/Feb/2014:09:53:55 -0600] cupsdReadClient: 13 WAITING Closing on
EOF
D [04/Feb/2014:09:53:55 -0600] cupsdCloseClient: 13

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.205252,  3] smbd/error.c:81(error_packet_set)
  error packet at smbd/ipc.c(137) cmd=37 (SMBtrans)
STATUS_BUFFER_OVERFLOW
[2014/02/04 09:53:55.205942,  3] smbd/process.c:1662(process_smb)
  Transaction 917 of length 63 (0 toread)
[2014/02/04 09:53:55.206107,  3] smbd/process.c:1467(switch_message)
  switch message SMBreadX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.206229,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.206404,  3] smbd/pipes.c:485(pipe_read_andx_done)
  readX-IPC min=3112 max=3112 nread=3112
[2014/02/04 09:53:55.207583,  3] smbd/process.c:1662(process_smb)
  Transaction 918 of length 4244 (0 toread)
[2014/02/04 09:53:55.207751,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.207874,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.207988,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.208090,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387c)
[2014/02/04 09:53:55.208217,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.209846,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.210015,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.210155,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.210292,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.210624,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.212079,  3] smbd/process.c:1662(process_smb)
  Transaction 919 of length 132 (0 toread)
[2014/02/04 09:53:55.212244,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.212404,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=44 params=0 setup=2
[2014/02/04 09:53:55.212904,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.213105,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387c)
[2014/02/04 09:53:55.213237,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2014/02/04 09:53:55.213389,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.213651,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 29
[2014/02/04 09:53:55.214523,  3] smbd/process.c:1662(process_smb)
  Transaction 920 of length 45 (0 toread)
[2014/02/04 09:53:55.214687,  3] smbd/process.c:1467(switch_message)
  switch message SMBclose (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.214800,  3] smbd/reply.c:4848(reply_close)
  close fd=-1 fnum=14460 (numopen=3)
[2014/02/04 09:53:55.215511,  3] smbd/process.c:1662(process_smb)
  Transaction 921 of length 106 (0 toread)
[2014/02/04 09:53:55.215685,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.216396,  3] smbd/process.c:1662(process_smb)
  Transaction 922 of length 88 (0 toread)
[2014/02/04 09:53:55.216561,  3] smbd/process.c:1467(switch_message)
  switch message SMBnttrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.216676,  3] smbd/error.c:81(error_packet_set)
  error packet at smbd/nttrans.c(3118) cmd=160 (SMBnttrans)
NT_STATUS_ACCESS_DENIED
[2014/02/04 09:53:55.217311,  3] smbd/process.c:1662(process_smb)
  Transaction 923 of length 228 (0 toread)
[2014/02/04 09:53:55.217504,  3] smbd/process.c:1467(switch_message)
  switch message SMBwriteX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.217637,  3]
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/02/04 09:53:55.217757,  3]
rpc_server/srv_pipe.c:339(check_bind_req)
  check_bind_req for \spoolss
[2014/02/04 09:53:55.217864,  3]
rpc_server/srv_pipe.c:346(check_bind_req)
  check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/02/04 09:53:55.218012,  3] smbd/pipes.c:361(pipe_write_andx_done)
  writeX-IPC nwritten=160
[2014/02/04 09:53:55.218637,  3] smbd/process.c:1662(process_smb)
  Transaction 924 of length 63 (0 toread)
[2014/02/04 09:53:55.218801,  3] smbd/process.c:1467(switch_message)
  switch message SMBreadX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.218920,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 29
[2014/02/04 09:53:55.219044,  3] smbd/pipes.c:485(pipe_read_andx_done)
  readX-IPC min=1024 max=1024 nread=68
[2014/02/04 09:53:55.219625,  3] smbd/process.c:1662(process_smb)
  Transaction 925 of length 1534 (0 toread)
[2014/02/04 09:53:55.219767,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.219885,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=1446 params=0 setup=2
[2014/02/04 09:53:55.219998,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.220104,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.220292,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
  checking name: \\printer-server\Savin-Main
[2014/02/04 09:53:55.220510,  3]
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
  Setting printer type=\\printer-server\Savin-Main
[2014/02/04 09:53:55.220678,  3] lib/access.c:338(allow_access)
  Allowed connection from 10.161.1.32 (10.161.1.32)

==> samba/log.winbindd <==
[2014/02/04 09:53:55.220911,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.221283,
3] ../libcli/security/dom_sid.c:208(dom_sid_parse_endp)
  string_to_sid: SID root is not in a valid format

==> samba/log.winbindd <==
[2014/02/04 09:53:55.221605,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.221820,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.221982,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.222182,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.windows-server-2 <==
[2014/02/04 09:53:55.223124,  2]
rpc_client/cli_winreg_spoolss.c:896(winreg_create_printer)
  winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows
NT\CurrentVersion\Print\Printers\Savin-Main already exists
[2014/02/04 09:53:55.223342,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.223499,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.223670,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 1679
[2014/02/04 09:53:55.240281,  3] smbd/process.c:1662(process_smb)
  Transaction 926 of length 4244 (0 toread)
[2014/02/04 09:53:55.240520,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.240670,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.240789,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.240892,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.241029,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.242736,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.242908,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.243047,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.243186,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.243535,  3] smbd/error.c:81(error_packet_set)
  error packet at smbd/ipc.c(137) cmd=37 (SMBtrans)
STATUS_BUFFER_OVERFLOW
[2014/02/04 09:53:55.244159,  3] smbd/process.c:1662(process_smb)
  Transaction 927 of length 63 (0 toread)
[2014/02/04 09:53:55.244361,  3] smbd/process.c:1467(switch_message)
  switch message SMBreadX (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.244487,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.244632,  3] smbd/pipes.c:485(pipe_read_andx_done)
  readX-IPC min=3112 max=3112 nread=3112
[2014/02/04 09:53:55.245617,  3] smbd/process.c:1662(process_smb)
  Transaction 928 of length 4244 (0 toread)
[2014/02/04 09:53:55.245785,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.245924,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.246038,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.246140,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.246312,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.247900,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.248070,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.248239,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.248388,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.248680,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.251396,  3] smbd/process.c:1662(process_smb)
  Transaction 929 of length 4244 (0 toread)
[2014/02/04 09:53:55.251563,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.251685,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.251798,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.251917,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.252046,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.253656,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.253824,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.254004,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.254144,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.254473,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.255598,  3] smbd/process.c:1662(process_smb)
  Transaction 930 of length 4244 (0 toread)
[2014/02/04 09:53:55.255885,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.256112,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.256809,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.256975,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.257118,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.258801,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.258989,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.259159,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.259309,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.259607,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.268333,  3] smbd/process.c:1662(process_smb)
  Transaction 931 of length 4244 (0 toread)
[2014/02/04 09:53:55.268503,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.268629,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.268742,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.268843,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.268990,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.270951,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.271160,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.271307,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.271445,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.271736,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.272919,  3] smbd/process.c:1662(process_smb)
  Transaction 932 of length 4244 (0 toread)
[2014/02/04 09:53:55.273140,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.273268,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.273382,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.273483,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.273611,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.275269,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.275497,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.275770,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.276039,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.276610,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.280600,  3] smbd/process.c:1662(process_smb)
  Transaction 933 of length 4244 (0 toread)
[2014/02/04 09:53:55.280773,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.280928,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.281131,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.281270,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.281435,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.284138,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.284481,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.284770,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.285135,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.285563,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.286880,  3] smbd/process.c:1662(process_smb)
  Transaction 934 of length 4244 (0 toread)
[2014/02/04 09:53:55.287121,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.287250,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.287363,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.287464,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.287603,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.289252,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.289422,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.289561,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.289699,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.289989,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.291215,  3] smbd/process.c:1662(process_smb)
  Transaction 935 of length 4244 (0 toread)
[2014/02/04 09:53:55.291382,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.291505,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.291618,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.291725,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.291873,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.293497,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.293667,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.293807,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.293945,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.294277,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.295431,  3] smbd/process.c:1662(process_smb)
  Transaction 936 of length 4244 (0 toread)
[2014/02/04 09:53:55.295598,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.295719,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.295833,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.295941,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.296126,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.297714,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.297884,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.298069,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.298212,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.298502,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.311582,  3] smbd/process.c:1662(process_smb)
  Transaction 937 of length 4244 (0 toread)
[2014/02/04 09:53:55.311750,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.311873,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.312029,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.312151,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.312294,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.313889,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.314086,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.314226,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.314364,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.314671,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.315720,  3] smbd/process.c:1662(process_smb)
  Transaction 938 of length 4244 (0 toread)
[2014/02/04 09:53:55.315916,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.316052,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.316165,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.316318,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.316455,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.318079,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.318249,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.318390,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.318527,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.318817,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.322437,  3] smbd/process.c:1662(process_smb)
  Transaction 939 of length 4244 (0 toread)
[2014/02/04 09:53:55.322604,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.322727,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.322839,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.323004,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.323136,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.324719,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.324940,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.325121,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.325267,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.325561,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.326802,  3] smbd/process.c:1662(process_smb)
  Transaction 940 of length 4244 (0 toread)
[2014/02/04 09:53:55.327009,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.327134,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.327248,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.327373,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.327508,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.329410,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.329580,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.329719,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.329882,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.330184,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.331366,  3] smbd/process.c:1662(process_smb)
  Transaction 941 of length 4244 (0 toread)
[2014/02/04 09:53:55.331547,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.331668,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.331792,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.331990,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.332127,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.334615,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.334905,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.335048,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.335186,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.335500,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.336934,  3] smbd/process.c:1662(process_smb)
  Transaction 942 of length 4244 (0 toread)
[2014/02/04 09:53:55.337122,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.337254,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.337370,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.337473,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.337603,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.339253,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.339422,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.339562,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.339699,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.340027,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.341201,  3] smbd/process.c:1662(process_smb)
  Transaction 943 of length 4244 (0 toread)
[2014/02/04 09:53:55.341368,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.341492,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.341624,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.341731,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.341904,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.343494,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.343663,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.343870,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.344016,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.344307,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.345441,  3] smbd/process.c:1662(process_smb)
  Transaction 944 of length 4244 (0 toread)
[2014/02/04 09:53:55.345626,  3] smbd/process.c:1467(switch_message)
  switch message SMBtrans (pid 2231) conn 0x7f27009db360
[2014/02/04 09:53:55.345749,  3] smbd/ipc.c:560(handle_trans)
  trans <\PIPE\> data=4156 params=0 setup=2
[2014/02/04 09:53:55.345903,  3] smbd/ipc.c:511(named_pipe)
  named pipe command on <> name
[2014/02/04 09:53:55.346023,  3] smbd/ipc.c:475(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 387d)
[2014/02/04 09:53:55.346151,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/02/04 09:53:55.347896,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.348069,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.348218,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.348357,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy
[2014/02/04 09:53:55.348645,  3]
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 4681
[2014/02/04 09:53:55.353497,  3] smbd/process.c:1662(process_smb)
  Transaction 945 of length 106 (0 toread)
[2014/02/04 09:53:55.353668,  3] smbd/process.c:1467(switch_message)
  switch message SMBntcreateX (pid 2231) conn 0x7f27009db360

==> samba/log.winbindd <==
[2014/02/04 09:53:55.358423,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.359217,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.359441,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.359626,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.359873,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.winbindd <==
[2014/02/04 09:53:55.504910,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.505620,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.505844,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.506031,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.506277,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.winbindd <==
[2014/02/04 09:53:55.701614,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.702323,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.702552,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.702738,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.702952,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.winbindd <==
[2014/02/04 09:53:55.713970,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.714607,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.714814,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.714975,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.715204,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.winbindd <==
[2014/02/04 09:53:55.726227,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.726985,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.727237,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.727413,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.728304,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy

==> samba/log.winbindd <==
[2014/02/04 09:53:55.736597,  3]
winbindd/winbindd_getpwuid.c:47(winbindd_getpwuid_send)
  getpwuid 16777216
[2014/02/04 09:53:55.737280,  3]
winbindd/winbindd_lookupname.c:69(winbindd_lookupname_send)
  lookupname Unix User\root

==> samba/log.wb-PRINTER-SERVER <==
[2014/02/04 09:53:55.737535,  3]
winbindd/winbindd_samr.c:622(sam_name_to_sid)
  sam_name_to_sid
[2014/02/04 09:53:55.737733,  3]
winbindd/winbindd_rpc.c:303(rpc_name_to_sid)
  name_to_sid: UNIX USER\ROOT for domain UNIX USER
[2014/02/04 09:53:55.737943,  3]
rpc_server/rpc_handles.c:281(close_policy_hnd)
  Closed policy


-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 490 bytes
Desc: not available
URL: <http://lists.samba.org/pipermail/samba/attachments/20140204/1cb37f48/attachment.pgp>


More information about the samba mailing list