[Samba] samba 3.6.24 domain member as printserver in win2008/2012 domain: Access denied

problem1 at proxyma3.eu problem1 at proxyma3.eu
Fri Nov 28 07:03:18 MST 2014


Hello everyone on the list,

I'm trying to set up a samba server as a print server for a special 
purpose.
The aim is to have a special printer which takes the printjobs to hand 
it over to a script for further processing.
I have this running an an old samba server running 3.4.9.

The new server with samba 3.6.24 is configured to be a win2008/2012 
domain member using MIT kerberos.
The domain join was succesfull. I can do "wbingo -u" and "wbinfo -g" to 
see the domain users and groups.
Also "getent passwd <Domainuser>" and "id <Domainuser>" are working.

Here's my config:

nsswitch.conf:
passwd:      compat winbind
shadow:      compat winbind
group:       compat winbind
hosts:       files dns
#hosts:       files dns wins
networks:    files dns
services:    db files
protocols:   db files
rpc:         db files
ethers:      db files
netmasks:    files
netgroup:    files
bootparams:  files
automount:   files
aliases:     files

krb5.conf:
[libdefaults]
         default_realm = DOMAIN.LOCAL
         default_tgs_enctypes = arcfour-hmac-md5 des-cbc-crc des-cbc-md5 
des3-hmac-sha1
         default_tkt_enctypes = arcfour-hmac-md5 des-cbc-crc des-cbc-md5 
des3-hmac-sha1
         clockskew = 300
         kdc_timesync = 1
         ccache_type = 4
         forwardable = true
         proxiable = true
         fcc-mit-ticketflags = true
         default_keytab_name = FILE:/etc/krb5.keytab
[realms]
# use "kdc = ..." if realm admins haven't put SRV records into DNS
         domain.LOCAL = {
                 default_domain = domain.local
         }
[domain_realm]
         domain.local = DOMAIN.LOCAL
         .domain.local = DOMAIN.LOCAL
[appdefaults]
   pam = {
       debug = false
       ticket_lifetime = 1d
       renew_lifetime = 1d
       forwardable = true
       proxiable = false
       retain_after_close = false
       minimum_uid = 500
       try_first_pass = true
   }
[logging]
#       kdc = CONSOLE
       default = FILE:/var/log/krb5libs.log
       kdc = FILE:/var/log/krb5kdc.log
       admin_server = FILE:/var/log/kadmind.log

smb.conf:
[global]
     workgroup = DOMAIN
     server string = Samba Server
     netbios name = li27x245
# --------------------------- Logging Options 
-----------------------------
     log level = 3
     # logs split per machine
     log file = /var/log/samba/%m.log
     # max 5000KB per log file, then rotate
     max log size = 5000
# ----------------------- Domain Members Options 
------------------------
#
     security = ADS
     realm = DOMAIN.LOCAL
     kerberos method = secrets and keytab
     winbind refresh tickets = yes
     encrypt passwords = Yes
     winbind enum users = Yes
     winbind enum groups = Yes
     winbind use default domain = Yes
     winbind nested groups = Yes
     winbind separator = +
     idmap config *:backend = tdb
     idmap config *:range = 20000-39999
     idmap config DOMAIN:backend = rid
     idmap config DOMAIN:range = 600-19999
     idmap config DOMAIN:base_id = 0
     idmap config DOMAIN:default = yes

     template homedir = /home/%U
     template shell = /sbin/nologin
     allow trusted domains = No
     server signing = mandatory
     client signing = mandatory
     client use spnego = Yes
     ntlm auth = Yes
     lanman auth = No
     username map = /etc/samba/users.map
# ----------------------- Browser Control Options 
----------------------------
     preferred master = No
     domain master = No
     local master = No
# --------------------------- Printing Options 
-----------------------------

     printing = cups
     printcap name = cups
     load printers = No

[test]
    available = Yes
    comment = Test Share
    path = /spooltmp
    writeable = Yes
    browseable = Yes
    invalid users = root
    create mask = 0660
    directory mask = 0770
    valid users = @DOMAIN+"domänen-benutzer", @DOMAIN+"domänen-admins", 
dom\änen-benutzer, dom\änen-admins
    root preexec = /anpassung/drucken/create_homedir %U

[print$]
    comment = Printer Drivers
    path = /var/lib/samba/printers
    browseable = Yes
    read only = Yes
    guest ok = No
    write list = root, @DOMAIN+"domänen-benutzer", 
@DOMAIN+"domänen-admins", dom\änen-benutzer, dom\änen-admins

[dkp8]
     comment = virtueller Drucker  Taris
     path = /spooltmp
     printing = sysv
     printable = Yes
     print command = /anpassung/drucken/dkp8 %s
     use client driver = Yes
     valid users = @DOMAIN+"domänen-benutzer", @DOMAIN+"domänen-admins", 
dom\änen-benutzer, dom\änen-admins

[dedv7]
     comment = virtueller Drucker  Taris
     path = /spooltmp
     printing = cups
     printable = Yes
     public = Yes
     guest ok = Yes
     print command = lp -c -d%p %s; rm %s
     lpq command = lpq -P%p
     lprm command = cancel %p-%j
     lppause command = lp -i %p-%j -H hold
     lpresume command = lp -i %p-%j -H resume
     queuepause command = disable %p
     queueresume command = enable %p
     use client driver = Yes
     valid users = @DOMAIN+"domänen-benutzer", @DOMAIN+"domänen-admins", 
dom\änen-benutzer, dom\änen-admins
     root preexec = /anpassung/drucken/create_homedir %U

As you can see I have the "special" printer dkp8 and a normal one called 
dedv7.  The reason why I use "printing = sysv" is that this was the only 
way to get this running on the old server.
Connection the test share works find frokm domain users.
If I try to add a new printer on a win2008 R2 machine, I enter 
\\<sambaIP>\dedv7 as remote printer.
Windows tells me that it has no suitable driver. Then I choose which 
driver to use. Then it tells me „access denied“.
I tried this as administrator and as user but it does not change 
anything. Access is denied and I can't see why. I also tried to do 
testings with “username map“. But this does not help either.
By the way, I tried to compile samba with and without cups support 
included but none worked.


Here the samba log for this client:

[2014/11/28 11:05:00.366774,  3] auth/user_util.c:402(map_username)
   Mapped user edv-ssc to S-1-5-21-2373934162-4044904657-2509859042-1576
[2014/11/28 11:05:00.367328,  3] auth/auth.c:219(check_ntlm_password)
   check_ntlm_password:  Checking password for unmapped user 
[DOMAIN]\[edv-ssc]@[WIN27X21] with the new password interface
[2014/11/28 11:05:00.367368,  3] auth/auth.c:222(check_ntlm_password)
   check_ntlm_password:  mapped user is: 
[DOMAIN]\[S-1-5-21-2373934162-4044904657-2509859042-1576]@[WIN27X21]
[2014/11/28 11:05:00.424975,  3] auth/auth.c:268(check_ntlm_password)
   check_ntlm_password: winbind authentication for user [edv-ssc] 
succeeded
[2014/11/28 11:05:00.425034,  2] auth/auth.c:309(check_ntlm_password)
   check_ntlm_password:  authentication for user [edv-ssc] -> 
[S-1-5-21-2373934162-4044904657-2509859042-1576] -> [DOMAIN+edv-ssc] 
succeeded
[2014/11/28 11:05:00.425645,  3] 
../libcli/auth/ntlmssp_sign.c:535(ntlmssp_sign_init)
   NTLMSSP Sign/Seal - Initialising with flags:
[2014/11/28 11:05:00.425689,  3] 
../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
   Got NTLMSSP neg_flags=0xe2088215
[2014/11/28 11:05:00.425734,  3] 
smbd/password.c:298(register_existing_vuid)
   register_existing_vuid: User name: DOMAIN+edv-ssc     Real name:
[2014/11/28 11:05:00.425764,  3] 
smbd/password.c:308(register_existing_vuid)
   register_existing_vuid: UNIX uid 600 is UNIX user DOMAIN+edv-ssc, and 
will be vuid 100
[2014/11/28 11:05:00.426026,  3] 
smbd/password.c:238(register_homes_share)
   Adding homes service for user 'DOMAIN+edv-ssc' using home directory: 
'/home/edv-ssc'
[2014/11/28 11:05:00.426067,  3] smbd/signing.c:267(srv_set_signing)
   srv_set_signing: turning on SMB signing: signing negotiated = 1, 
mandatory_signing = 1.
[2014/11/28 11:05:00.427430,  3] smbd/process.c:1609(process_smb)
   Transaction 3 of length 98 (0 toread)
[2014/11/28 11:05:00.427473,  3] smbd/process.c:1414(switch_message)
   switch message SMBtconX (pid 1405) conn 0x0
[2014/11/28 11:05:00.427522,  3] lib/access.c:338(allow_access)
   Allowed connection from 192.168.27.21 (192.168.27.21)
[2014/11/28 11:05:00.427570,  3] 
smbd/service.c:872(make_connection_snum)
   Connect path is '/tmp' for service [IPC$]
[2014/11/28 11:05:00.427618,  3] smbd/vfs.c:102(vfs_init_default)
   Initialising default vfs hooks
[2014/11/28 11:05:00.427677,  3] smbd/vfs.c:128(vfs_init_custom)
   Initialising custom vfs hooks from [/[Default VFS]/]
[2014/11/28 11:05:00.427888,  3] 
smbd/service.c:1114(make_connection_snum)
   win27x21 (192.168.27.21) signed connect to service IPC$ initially as 
user DOMAIN+edv-ssc (uid=600, gid=600) (pid 1405)
[2014/11/28 11:05:00.427946,  3] smbd/reply.c:871(reply_tcon_and_X)
   tconX service=IPC$
[2014/11/28 11:05:00.428391,  3] smbd/process.c:1609(process_smb)
   Transaction 4 of length 106 (0 toread)
[2014/11/28 11:05:00.428431,  3] smbd/process.c:1414(switch_message)
   switch message SMBntcreateX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.428940,  3] smbd/process.c:1609(process_smb)
   Transaction 5 of length 76 (0 toread)
[2014/11/28 11:05:00.428982,  3] smbd/process.c:1414(switch_message)
   switch message SMBtrans2 (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.430280,  3] smbd/process.c:1609(process_smb)
   Transaction 6 of length 228 (0 toread)
[2014/11/28 11:05:00.430320,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.430374,  3] 
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
   api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.430408,  3] 
rpc_server/srv_pipe.c:339(check_bind_req)
   check_bind_req for \spoolss
[2014/11/28 11:05:00.430439,  3] 
rpc_server/srv_pipe.c:346(check_bind_req)
   check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.430482,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=160
[2014/11/28 11:05:00.430880,  3] smbd/process.c:1609(process_smb)
   Transaction 7 of length 63 (0 toread)
[2014/11/28 11:05:00.430920,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.430955,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.431003,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=68
[2014/11/28 11:05:00.431482,  3] smbd/process.c:1609(process_smb)
   Transaction 8 of length 286 (0 toread)
[2014/11/28 11:05:00.431523,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.431562,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
   checking name: \\192.168.27.245\dedv7
[2014/11/28 11:05:00.431625,  3] 
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
   Setting printer type=\\192.168.27.245\dedv7
[2014/11/28 11:05:00.431720,  3] lib/access.c:338(allow_access)
   Allowed connection from 192.168.27.21 (192.168.27.21)
[2014/11/28 11:05:00.432306,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.432359,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.432584,  2] 
rpc_client/cli_winreg_spoolss.c:896(winreg_create_printer)
   winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows 
NT\CurrentVersion\Print\Printers\dedv7 already exists
[2014/11/28 11:05:00.432627,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.432668,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.432714,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=218
[2014/11/28 11:05:00.440082,  3] smbd/process.c:1609(process_smb)
   Transaction 9 of length 63 (0 toread)
[2014/11/28 11:05:00.440127,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.440165,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 2309
[2014/11/28 11:05:00.440204,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.447512,  3] smbd/process.c:1609(process_smb)
   Transaction 10 of length 106 (0 toread)
[2014/11/28 11:05:00.447565,  3] smbd/process.c:1414(switch_message)
   switch message SMBntcreateX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.448060,  3] smbd/process.c:1609(process_smb)
   Transaction 11 of length 228 (0 toread)
[2014/11/28 11:05:00.448112,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.448155,  3] 
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
   api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.448187,  3] 
rpc_server/srv_pipe.c:339(check_bind_req)
   check_bind_req for \spoolss
[2014/11/28 11:05:00.448217,  3] 
rpc_server/srv_pipe.c:346(check_bind_req)
   check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.448259,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=160
[2014/11/28 11:05:00.455528,  3] smbd/process.c:1609(process_smb)
   Transaction 12 of length 63 (0 toread)
[2014/11/28 11:05:00.455575,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.455609,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.455646,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=68
[2014/11/28 11:05:00.456119,  3] smbd/process.c:1609(process_smb)
   Transaction 13 of length 274 (0 toread)
[2014/11/28 11:05:00.456161,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.456199,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
   checking name: \\192.168.27.245
[2014/11/28 11:05:00.456248,  3] 
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
   Setting printer type=\\192.168.27.245
[2014/11/28 11:05:00.456325,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=206
[2014/11/28 11:05:00.456690,  3] smbd/process.c:1609(process_smb)
   Transaction 14 of length 63 (0 toread)
[2014/11/28 11:05:00.456745,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.456784,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.456822,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.457254,  3] smbd/process.c:1609(process_smb)
   Transaction 15 of length 148 (0 toread)
[2014/11/28 11:05:00.457295,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.457333,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
[2014/11/28 11:05:00.457406,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=80
[2014/11/28 11:05:00.457939,  3] smbd/process.c:1609(process_smb)
   Transaction 16 of length 63 (0 toread)
[2014/11/28 11:05:00.457991,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.458031,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 2071
[2014/11/28 11:05:00.458069,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=324
[2014/11/28 11:05:00.458457,  3] smbd/process.c:1609(process_smb)
   Transaction 17 of length 112 (0 toread)
[2014/11/28 11:05:00.458513,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.458570,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2014/11/28 11:05:00.458610,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.458649,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=44
[2014/11/28 11:05:00.459008,  3] smbd/process.c:1609(process_smb)
   Transaction 18 of length 63 (0 toread)
[2014/11/28 11:05:00.459049,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.459086,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.459122,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.459493,  3] smbd/process.c:1609(process_smb)
   Transaction 19 of length 45 (0 toread)
[2014/11/28 11:05:00.459552,  3] smbd/process.c:1414(switch_message)
   switch message SMBclose (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.459588,  3] smbd/reply.c:4860(reply_close)
   close fd=-1 fnum=7602 (numopen=2)
[2014/11/28 11:05:00.460098,  3] smbd/process.c:1609(process_smb)
   Transaction 20 of length 112 (0 toread)
[2014/11/28 11:05:00.460149,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.460189,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2014/11/28 11:05:00.460222,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.460260,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=44
[2014/11/28 11:05:00.460748,  3] smbd/process.c:1609(process_smb)
   Transaction 21 of length 63 (0 toread)
[2014/11/28 11:05:00.460806,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.460876,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.460944,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.461913,  3] smbd/process.c:1609(process_smb)
   Transaction 22 of length 45 (0 toread)
[2014/11/28 11:05:00.461968,  3] smbd/process.c:1414(switch_message)
   switch message SMBclose (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.462008,  3] smbd/reply.c:4860(reply_close)
   close fd=-1 fnum=7601 (numopen=1)
[2014/11/28 11:05:00.463220,  3] smbd/process.c:1609(process_smb)
   Transaction 23 of length 106 (0 toread)
[2014/11/28 11:05:00.463270,  3] smbd/process.c:1414(switch_message)
   switch message SMBntcreateX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.463683,  3] smbd/process.c:1609(process_smb)
   Transaction 24 of length 76 (0 toread)
[2014/11/28 11:05:00.463733,  3] smbd/process.c:1414(switch_message)
   switch message SMBtrans2 (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.464187,  3] smbd/process.c:1609(process_smb)
   Transaction 25 of length 228 (0 toread)
[2014/11/28 11:05:00.464254,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.464296,  3] 
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
   api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.464343,  3] 
rpc_server/srv_pipe.c:339(check_bind_req)
   check_bind_req for \spoolss
[2014/11/28 11:05:00.464381,  3] 
rpc_server/srv_pipe.c:346(check_bind_req)
   check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.464422,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=160
[2014/11/28 11:05:00.464774,  3] smbd/process.c:1609(process_smb)
   Transaction 26 of length 63 (0 toread)
[2014/11/28 11:05:00.464817,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.464860,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.464897,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=68
[2014/11/28 11:05:00.465333,  3] smbd/process.c:1609(process_smb)
   Transaction 27 of length 286 (0 toread)
[2014/11/28 11:05:00.465381,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.465420,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
   checking name: \\192.168.27.245\dedv7
[2014/11/28 11:05:00.465475,  3] 
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
   Setting printer type=\\192.168.27.245\dedv7
[2014/11/28 11:05:00.465574,  3] lib/access.c:338(allow_access)
   Allowed connection from 192.168.27.21 (192.168.27.21)
[2014/11/28 11:05:00.466243,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.466314,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.466562,  2] 
rpc_client/cli_winreg_spoolss.c:896(winreg_create_printer)
   winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows 
NT\CurrentVersion\Print\Printers\dedv7 already exists
[2014/11/28 11:05:00.466611,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.466676,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.466738,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=218
[2014/11/28 11:05:00.467100,  3] smbd/process.c:1609(process_smb)
   Transaction 28 of length 63 (0 toread)
[2014/11/28 11:05:00.467141,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.467178,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 2309
[2014/11/28 11:05:00.467217,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.468263,  3] smbd/process.c:1609(process_smb)
   Transaction 29 of length 4224 (0 toread)
[2014/11/28 11:05:00.468322,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.468381,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/11/28 11:05:00.469271,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.469350,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.469420,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.469489,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.469788,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=4156
[2014/11/28 11:05:00.470180,  3] smbd/process.c:1609(process_smb)
   Transaction 30 of length 63 (0 toread)
[2014/11/28 11:05:00.470235,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.470297,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=1024
[2014/11/28 11:05:00.470743,  3] smbd/process.c:1609(process_smb)
   Transaction 31 of length 63 (0 toread)
[2014/11/28 11:05:00.470786,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.470843,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 5257
[2014/11/28 11:05:00.470898,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=3112 max=3112 nread=3112
[2014/11/28 11:05:00.471750,  3] smbd/process.c:1609(process_smb)
   Transaction 32 of length 112 (0 toread)
[2014/11/28 11:05:00.471807,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.471860,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2014/11/28 11:05:00.471893,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.471931,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=44
[2014/11/28 11:05:00.472258,  3] smbd/process.c:1609(process_smb)
   Transaction 33 of length 63 (0 toread)
[2014/11/28 11:05:00.472300,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.472336,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.472373,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=4136 max=4136 nread=48
[2014/11/28 11:05:00.472758,  3] smbd/process.c:1609(process_smb)
   Transaction 34 of length 45 (0 toread)
[2014/11/28 11:05:00.472800,  3] smbd/process.c:1414(switch_message)
   switch message SMBclose (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.472830,  3] smbd/reply.c:4860(reply_close)
   close fd=-1 fnum=7603 (numopen=1)
[2014/11/28 11:05:00.474277,  3] smbd/process.c:1609(process_smb)
   Transaction 35 of length 106 (0 toread)
[2014/11/28 11:05:00.474319,  3] smbd/process.c:1414(switch_message)
   switch message SMBntcreateX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.474689,  3] smbd/process.c:1609(process_smb)
   Transaction 36 of length 76 (0 toread)
[2014/11/28 11:05:00.474730,  3] smbd/process.c:1414(switch_message)
   switch message SMBtrans2 (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.475179,  3] smbd/process.c:1609(process_smb)
   Transaction 37 of length 228 (0 toread)
[2014/11/28 11:05:00.475243,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.475302,  3] 
rpc_server/srv_pipe.c:889(api_pipe_bind_req)
   api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.475335,  3] 
rpc_server/srv_pipe.c:339(check_bind_req)
   check_bind_req for \spoolss
[2014/11/28 11:05:00.475365,  3] 
rpc_server/srv_pipe.c:346(check_bind_req)
   check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2014/11/28 11:05:00.475425,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=160
[2014/11/28 11:05:00.475835,  3] smbd/process.c:1609(process_smb)
   Transaction 38 of length 63 (0 toread)
[2014/11/28 11:05:00.475886,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.475921,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:05:00.475960,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=68
[2014/11/28 11:05:00.476796,  3] smbd/process.c:1609(process_smb)
   Transaction 39 of length 286 (0 toread)
[2014/11/28 11:05:00.476852,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.476897,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
   checking name: \\192.168.27.245\dedv7
[2014/11/28 11:05:00.476977,  3] 
rpc_server/spoolss/srv_spoolss_nt.c:521(set_printer_hnd_printertype)
   Setting printer type=\\192.168.27.245\dedv7
[2014/11/28 11:05:00.477067,  3] lib/access.c:338(allow_access)
   Allowed connection from 192.168.27.21 (192.168.27.21)
[2014/11/28 11:05:00.477683,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.477739,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.477965,  2] 
rpc_client/cli_winreg_spoolss.c:896(winreg_create_printer)
   winreg_create_printer: Skipping, SOFTWARE\Microsoft\Windows 
NT\CurrentVersion\Print\Printers\dedv7 already exists
[2014/11/28 11:05:00.478008,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.478067,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.478131,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=218
[2014/11/28 11:05:00.478525,  3] smbd/process.c:1609(process_smb)
   Transaction 40 of length 63 (0 toread)
[2014/11/28 11:05:00.478566,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.478620,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 2309
[2014/11/28 11:05:00.478678,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=48
[2014/11/28 11:05:00.479524,  3] smbd/process.c:1609(process_smb)
   Transaction 41 of length 4224 (0 toread)
[2014/11/28 11:05:00.479583,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.479638,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
[2014/11/28 11:05:00.480350,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.480404,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.480448,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.480518,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:05:00.480639,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=4156
[2014/11/28 11:05:00.481041,  3] smbd/process.c:1609(process_smb)
   Transaction 42 of length 63 (0 toread)
[2014/11/28 11:05:00.481083,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.481127,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=1024 max=1024 nread=1024
[2014/11/28 11:05:00.481595,  3] smbd/process.c:1609(process_smb)
   Transaction 43 of length 63 (0 toread)
[2014/11/28 11:05:00.481646,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:05:00.481681,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 5257
[2014/11/28 11:05:00.481735,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=3112 max=3112 nread=3112
[2014/11/28 11:06:55.600996,  3] smbd/process.c:1609(process_smb)
   Transaction 44 of length 112 (0 toread)
[2014/11/28 11:06:55.601097,  3] smbd/process.c:1414(switch_message)
   switch message SMBwriteX (pid 1405) conn 0xf40940
[2014/11/28 11:06:55.601167,  3] rpc_server/srv_pipe.c:1626(api_rpcTNP)
   api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
[2014/11/28 11:06:55.601204,  3] 
rpc_server/rpc_handles.c:281(close_policy_hnd)
   Closed policy
[2014/11/28 11:06:55.601248,  3] smbd/pipes.c:361(pipe_write_andx_done)
   writeX-IPC nwritten=44
[2014/11/28 11:06:55.601693,  3] smbd/process.c:1609(process_smb)
   Transaction 45 of length 63 (0 toread)
[2014/11/28 11:06:55.601735,  3] smbd/process.c:1414(switch_message)
   switch message SMBreadX (pid 1405) conn 0xf40940
[2014/11/28 11:06:55.601776,  3] 
rpc_server/srv_pipe_hnd.c:121(free_pipe_context)
   free_pipe_context: destroying talloc pool of size 23
[2014/11/28 11:06:55.601850,  3] smbd/pipes.c:485(pipe_read_andx_done)
   readX-IPC min=4136 max=4136 nread=48
[2014/11/28 11:06:55.602669,  3] smbd/process.c:1609(process_smb)
   Transaction 46 of length 45 (0 toread)
[2014/11/28 11:06:55.602712,  3] smbd/process.c:1414(switch_message)
   switch message SMBclose (pid 1405) conn 0xf40940
[2014/11/28 11:06:55.602759,  3] smbd/reply.c:4860(reply_close)
   close fd=-1 fnum=7604 (numopen=1)


So maybe someone can give me a hint to the missed information what I am 
doing wrong?
Please help me I'm going to go insane.

Thank you very much!
Sven



More information about the samba mailing list