[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)