Attachment 5785 Details for Bug 7506 – log.smbd

log.smbd

log.smbd (text/plain), 73.73 KB, created by Dev Null on 2010-06-09 18:06:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Dev Null

Created: 2010-06-09 18:06:12 UTC

Size: 73.73 KB

patch

obsolete

>[2010/06/08 09:54:56,  3] smbd/oplock.c:init_oplocks(912)
>  init_oplocks: initializing messages.
>[2010/06/08 09:54:56,  3] smbd/oplock_linux.c:linux_init_kernel_oplocks(241)
>  Linux kernel oplocks enabled
>[2010/06/08 09:54:56,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:54:56,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:54:56,  0] lib/util_sock.c:matchname(1749)
>  matchname: host name/address mismatch: ::ffff:134.114.138.126 != acadts.itslab.nau.edu
>[2010/06/08 09:54:56,  0] lib/util_sock.c:get_peer_name(1870)
>  Matchname failed on acadts.itslab.nau.edu ::ffff:134.114.138.126
>[2010/06/08 09:54:56,  2] lib/access.c:check_access(406)
>  Allowed connection from UNKNOWN (::ffff:134.114.138.126)
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 0 of length 137 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBnegprot (pid 9256) conn 0x0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN1.0]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [Windows for Workgroups 3.1a]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LM1.2X002]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [LANMAN2.1]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(569)
>  Requested protocol [NT LM 0.12]
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_nt1(392)
>  using SPNEGO
>[2010/06/08 09:54:56,  3] smbd/negprot.c:reply_negprot(674)
>  Selected protocol NT LM 0.12
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 1 of length 1844 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBsesssetupX (pid 9256) conn 0x0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sesssetup.c:reply_sesssetup_and_X(1412)
>  wct=12 flg2=0xc807
>[2010/06/08 09:54:56,  2] smbd/sesssetup.c:setup_new_vc_session(1368)
>  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
>[2010/06/08 09:54:56,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1175)
>  Doing spnego session setup
>[2010/06/08 09:54:56,  3] smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1210)
>  NativeOS=[] NativeLanMan=[] PrimaryDomain=[]
>[2010/06/08 09:54:56,  3] smbd/sesssetup.c:reply_spnego_negotiate(802)
>  reply_spnego_negotiate: Got secblob of size 1711
>[2010/06/08 09:54:56,  3] libads/authdata.c:decode_pac_data(304)
>  Found account name from PAC: rbm43 [Rob Moser]
>[2010/06/08 09:54:56,  3] smbd/sesssetup.c:reply_spnego_kerberos(356)
>  Ticket name is [rbm43@STUDENTS.FROOT.NAU.EDU]
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-312544]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-513]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-79401]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-107019]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-147250]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-117609710-1078145449-682003330-83146]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2129867641-1992771036-1243820751-571]
>[2010/06/08 09:54:56,  3] lib/privileges.c:get_privileges(63)
>  get_privileges: No privileges assigned to SID [S-1-5-32-545]
>[2010/06/08 09:54:56,  3] smbd/password.c:register_existing_vuid(289)
>  register_existing_vuid: User name: NAU-STUDENTS\rbm43	Real name: Rob Moser
>[2010/06/08 09:54:56,  3] smbd/password.c:register_existing_vuid(299)
>  register_existing_vuid: UNIX uid 111263 is UNIX user NAU-STUDENTS\rbm43, and will be vuid 101
>[2010/06/08 09:54:56,  3] smbd/password.c:register_homes_share(231)
>  Adding homes service for user 'NAU-STUDENTS\rbm43' using home directory: '/home/NAU-STUDENTS/rbm43'
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 2 of length 100 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtconX (pid 9256) conn 0x0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:54:56,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:54:56,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:54:56,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/service.c:make_connection_snum(878)
>  Connect path is '/tmp' for service [IPC$]
>[2010/06/08 09:54:56,  3] smbd/vfs.c:vfs_init_default(96)
>  Initialising default vfs hooks
>[2010/06/08 09:54:56,  3] smbd/vfs.c:vfs_init_custom(130)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/06/08 09:54:56,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/service.c:make_connection_snum(1134)
>  __ffff_134.114.138.126 (::ffff:134.114.138.126) connect to service IPC$ initially as user NAU-STUDENTS\rbm43 (uid=111263, gid=10004) (pid 9256)
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/reply.c:reply_tcon_and_X(799)
>  tconX service=IPC$ 
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 3 of length 106 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 4 of length 228 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71c8 nwritten=160
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 5 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71c8 min=1024 max=1024 nread=68
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 6 of length 312 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71c8)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:54:56,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:54:56,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:54:56,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 7 of length 4244 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=4156 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71c8)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:54:56,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/ipc.c(128) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 8 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71c8 min=3112 max=3112 nread=3112
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 9 of length 106 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 10 of length 228 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71c9 nwritten=160
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 11 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71c9 min=1024 max=1024 nread=68
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 12 of length 312 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71c9)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:54:56,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:54:56,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:54:56,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 13 of length 4244 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=4156 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71c9)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:54:56,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/ipc.c(128) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 14 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71c9 min=3112 max=3112 nread=3112
>[2010/06/08 09:54:56,  3] printing/printing.c:print_queue_update_internal(1156)
>  print_queue_update_internal: 0 jobs in queue for ACLJ
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 15 of length 106 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 16 of length 228 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71ca nwritten=160
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 17 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71ca min=1024 max=1024 nread=68
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 18 of length 312 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:54:56,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:54:56,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:54:56,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:54:56,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:54:56,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 19 of length 148 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 20 of length 148 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 21 of length 4348 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71ca nwritten=4280
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 22 of length 4348 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71ca nwritten=4280
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 23 of length 876 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=788 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:54:56,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/ipc.c(128) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 24 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71ca min=3256 max=3256 nread=3256
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 25 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71ca min=4280 max=4280 nread=4280
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 26 of length 63 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71ca min=4280 max=4280 nread=772
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 27 of length 176 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 1050
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 28 of length 2244 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2156 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDRIVER2
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 80
>[2010/06/08 09:54:56,  3] smbd/process.c:process_smb(1576)
>  Transaction 29 of length 2196 (0 toread)
>[2010/06/08 09:54:56,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:54:56,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:54:56,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:54:56,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:54:56,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:01,  1] lib/debug.c:debuglevel_message(532)
>  INFO: Received REQ_DEBUGLEVEL message from PID 9257
>[2010/06/08 09:55:01,  1] lib/debug.c:debuglevel_message(532)
>  INFO: Received REQ_DEBUGLEVEL message from PID 9257
>[2010/06/08 09:55:01,  1] lib/debug.c:debuglevel_message(532)
>  INFO: Received REQ_DEBUGLEVEL message from PID 9257
>[2010/06/08 09:55:01,  1] lib/debug.c:debuglevel_message(532)
>  INFO: Received REQ_DEBUGLEVEL message from PID 9257
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 30 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 31 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 32 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 33 of length 140 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=52 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 34 of length 106 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 35 of length 228 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71cb nwritten=160
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 36 of length 63 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71cb min=1024 max=1024 nread=68
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 37 of length 312 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cb)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:03,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:03,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:55:03,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 38 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 39 of length 106 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 40 of length 228 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71cc nwritten=160
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 41 of length 63 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71cc min=1024 max=1024 nread=68
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 42 of length 312 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cc)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:03,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:03,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:55:03,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 43 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 44 of length 144 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=56 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cc)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 45 of length 332 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=244 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cc)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 46 of length 132 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cc)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(209)
>  Closed policy
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 47 of length 45 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 48 of length 106 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 49 of length 228 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71cd nwritten=160
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 50 of length 63 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71cd min=1024 max=1024 nread=68
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 51 of length 312 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cd)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:03,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:03,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:55:03,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 52 of length 2196 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ca)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 56
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 53 of length 106 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBntcreateX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/nttrans.c:nt_open_pipe(286)
>  nt_open_pipe: Known pipe spoolss opening.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 54 of length 228 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBwriteX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_pipe_bind_req(1617)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:check_bind_req(991)
>  check_bind_req for spoolss
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_write_and_X(241)
>  writeX-IPC pnum=71ce nwritten=160
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 55 of length 63 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBreadX (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/pipes.c:reply_pipe_read_and_X(291)
>  readX-IPC pnum=71ce min=1024 max=1024 nread=68
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 56 of length 312 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=224 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ce)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 184
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] rpc_server/srv_spoolss_nt.c:set_printer_hnd_printertype(427)
>  Setting printer type=\\dev-acadprtsrv2\aclj
>[2010/06/08 09:55:03,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:03,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:03,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.138.126 (::ffff:134.114.138.126)
>[2010/06/08 09:55:03,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(111263, 10004) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(101) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:03,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (111263, 10004) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_spoolss_nt.c:_spoolss_open_printer_ex(1722)
>  access DENIED for printer open
>[2010/06/08 09:55:03,  3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(209)
>  Closed policy
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 212
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 57 of length 132 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71ce)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/06/08 09:55:03,  2] rpc_server/srv_spoolss_nt.c:find_printer_index_by_hnd(273)
>  find_printer_index_by_hnd: Printer handle not found: find_printer_index_by_hnd: Printer handle not found: close_printer_handle: Invalid handle (OURS:9256:9256)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 58 of length 45 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 59 of length 132 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cd)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(209)
>  Closed policy
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 60 of length 45 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 61 of length 132 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71cb)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(209)
>  Closed policy
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 62 of length 45 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 63 of length 132 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtrans (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:03,  3] smbd/ipc.c:handle_trans(442)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/06/08 09:55:03,  3] smbd/ipc.c:named_pipe(393)
>  named pipe command on <> name
>[2010/06/08 09:55:03,  3] smbd/ipc.c:api_fd_reply(351)
>  Got API command 0x26 on pipe "spoolss" (pnum 71c9)
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe.c:api_rpcTNP(2359)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/06/08 09:55:03,  3] rpc_server/srv_lsa_hnd.c:close_policy_hnd(209)
>  Closed policy
>[2010/06/08 09:55:03,  3] rpc_server/srv_pipe_hnd.c:free_pipe_context(500)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/06/08 09:55:03,  3] smbd/process.c:process_smb(1576)
>  Transaction 64 of length 45 (0 toread)
>[2010/06/08 09:55:03,  3] smbd/process.c:switch_message(1393)
>  switch message SMBclose (pid 9256) conn 0x2b579950aae0
>[2010/06/08 09:55:09,  3] smbd/process.c:process_smb(1576)
>  Transaction 4 of length 100 (0 toread)
>[2010/06/08 09:55:09,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtconX (pid 9253) conn 0x0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:09,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:09,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.9.189 (::ffff:134.114.9.189)
>[2010/06/08 09:55:09,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  2] smbd/service.c:create_connection_server_info(667)
>  user 'plg9' (from session setup) not permitted to access this share (IPC$)
>[2010/06/08 09:55:09,  1] smbd/service.c:make_connection_snum(747)
>  create_connection_server_info failed: NT_STATUS_ACCESS_DENIED
>[2010/06/08 09:55:09,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/reply.c(734) cmd=117 (SMBtconX) NT_STATUS_ACCESS_DENIED
>[2010/06/08 09:55:09,  3] smbd/process.c:process_smb(1576)
>  Transaction 5 of length 100 (0 toread)
>[2010/06/08 09:55:09,  3] smbd/process.c:switch_message(1393)
>  switch message SMBtconX (pid 9253) conn 0x0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] lib/access.c:only_ipaddrs_in_list(362)
>  only_ipaddrs_in_list: list has non-ip address (127.)
>[2010/06/08 09:55:09,  3] lib/access.c:check_access(396)
>  check_access: hostnames in host allow/deny list.
>[2010/06/08 09:55:09,  2] lib/access.c:check_access(406)
>  Allowed connection from ::ffff:134.114.9.189 (::ffff:134.114.9.189)
>[2010/06/08 09:55:09,  3] lib/util_sid.c:string_to_sid(228)
>  string_to_sid: Sid plg9 does not start with 'S-'.
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:push_sec_ctx(224)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/uid.c:push_conn_ctx(440)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:set_sec_ctx(324)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/06/08 09:55:09,  3] smbd/sec_ctx.c:pop_sec_ctx(432)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/06/08 09:55:09,  2] smbd/service.c:create_connection_server_info(667)
>  user 'plg9' (from session setup) not permitted to access this share (IPC$)
>[2010/06/08 09:55:09,  1] smbd/service.c:make_connection_snum(747)
>  create_connection_server_info failed: NT_STATUS_ACCESS_DENIED
>[2010/06/08 09:55:09,  3] smbd/error.c:error_packet_set(61)
>  error packet at smbd/reply.c(734) cmd=117 (SMBtconX) NT_STATUS_ACCESS_DENIED
>[2010/06/08 09:55:16,  3] lib/debug.c:debug_message(509)
>  INFO: Remote set of debug to `0'  (pid 9256 from pid 9262)
>[2010/06/08 09:55:16,  3] lib/debug.c:debug_message(509)
>  INFO: Remote set of debug to `0'  (pid 9227 from pid 9262)
>[2010/06/08 09:55:16,  3] lib/debug.c:debug_message(509)
>  INFO: Remote set of debug to `0'  (pid 9253 from pid 9262)
>[2010/06/08 09:55:16,  3] lib/debug.c:debug_message(509)
>  INFO: Remote set of debug to `0'  (pid 9235 from pid 9262)

Actions: View

Attachments on bug 7506: 5785 | 5786 | 5787