Attachment 5238 Details for Bug 7074 – Log for Stage 1: Not Working

Log for Stage 1: Not Working

api-stp-5pfglb1.log.1-notworking.txt (text/plain), 136.18 KB, created by John Holmstadt on 2010-01-28 16:15:39 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: John Holmstadt

Created: 2010-01-28 16:15:39 UTC

Size: 136.18 KB

patch

obsolete

>[2010/01/28 15:56:42,  3] auth/auth.c:222(check_ntlm_password)
>  check_ntlm_password:  Checking password for unmapped user [API-DOMAIN]\[jholmsta]@[API-STP-5PFGLB1] with the new password interface
>[2010/01/28 15:56:42,  3] auth/auth.c:225(check_ntlm_password)
>  check_ntlm_password:  mapped user is: [SMBTEST]\[jholmsta]@[API-STP-5PFGLB1]
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  2] lib/smbldap.c:856(smbldap_open_connection)
>  smbldap_open_connection: connection opened
>[2010/01/28 15:56:42,  3] lib/smbldap.c:1067(smbldap_connect_system)
>  ldap_connect_system: successful connection to the LDAP server
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:571(init_sam_from_ldap)
>  init_sam_from_ldap: Entry found for user: jholmsta
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 513
>[2010/01/28 15:56:42,  3] passdb/pdb_get_set.c:235(pdb_get_group_sid)
>  Primary group for user jholmsta is a UNKNOWN and not a domain group
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 513
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 512
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 1501
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 1510
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 1511
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 2576
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:42,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 1800
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] auth/auth.c:271(check_ntlm_password)
>  check_ntlm_password: sam authentication for user [jholmsta] succeeded
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  2] auth/auth.c:310(check_ntlm_password)
>  check_ntlm_password:  authentication for user [jholmsta] -> [jholmsta] -> [jholmsta] succeeded
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] auth/token_util.c:464(create_local_nt_token)
>  Failed to fetch domain sid for API-DOMAIN
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:42,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:42,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-12120]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-513]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-512]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-4003]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-4021]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-4023]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-6153]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-2804]
>[2010/01/28 15:56:43,  3] lib/privileges.c:63(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-2828578859-3132521847-1089271054-4601]
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] libsmb/ntlmssp_sign.c:342(ntlmssp_sign_init)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2010/01/28 15:56:43,  3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags)
>  Got NTLMSSP neg_flags=0xe2088215
>[2010/01/28 15:56:43,  3] smbd/password.c:269(register_existing_vuid)
>  register_existing_vuid: User name: jholmsta	Real name: John Holmstadt
>[2010/01/28 15:56:43,  3] smbd/password.c:279(register_existing_vuid)
>  register_existing_vuid: UNIX uid 5560 is UNIX user jholmsta, and will be vuid 100
>[2010/01/28 15:56:43,  3] smbd/password.c:211(register_homes_share)
>  Adding homes service for user 'jholmsta' using home directory: '/var/resource/users/jholmsta'
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 3 of length 84 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtconX (pid 27216) conn 0x0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/tmp' for service [IPC$]
>[2010/01/28 15:56:43,  3] smbd/vfs.c:95(vfs_init_default)
>  Initialising default vfs hooks
>[2010/01/28 15:56:43,  3] smbd/vfs.c:129(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:1047(make_connection_snum)
>  api-stp-5pfglb1 (::ffff:172.16.1.142) connect to service IPC$ initially as user jholmsta (uid=5560, gid=513) (pid 27216)
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/reply.c:759(reply_tcon_and_X)
>  tconX service=IPC$ 
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 4 of length 96 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:328(find_service)
>  checking for home directory . gave (NULL)
>[2010/01/28 15:56:43,  3] smbd/service.c:414(find_service)
>  find_service() failed to find service .
>[2010/01/28 15:56:43,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/trans2.c(7421) cmd=50 (SMBtrans2) NT_STATUS_NOT_FOUND
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 5 of length 78 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtconX (pid 27216) conn 0x0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:328(find_service)
>  checking for home directory . gave (NULL)
>[2010/01/28 15:56:43,  3] smbd/service.c:414(find_service)
>  find_service() failed to find service .
>[2010/01/28 15:56:43,  0] smbd/service.c:1188(make_connection)
>  api-stp-5pfglb1 (::ffff:172.16.1.142) couldn't find service .
>[2010/01/28 15:56:43,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/reply.c(689) cmd=117 (SMBtconX) NT_STATUS_BAD_NETWORK_NAME
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 6 of length 78 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtconX (pid 27216) conn 0x0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:328(find_service)
>  checking for home directory . gave (NULL)
>[2010/01/28 15:56:43,  3] smbd/service.c:414(find_service)
>  find_service() failed to find service .
>[2010/01/28 15:56:43,  0] smbd/service.c:1188(make_connection)
>  api-stp-5pfglb1 (::ffff:172.16.1.142) couldn't find service .
>[2010/01/28 15:56:43,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/reply.c(689) cmd=117 (SMBtconX) NT_STATUS_BAD_NETWORK_NAME
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 7 of length 104 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  2] passdb/pdb_ldap.c:2353(init_group_from_ldap)
>  init_group_from_ldap: Entry found for group: 513
>[2010/01/28 15:56:43,  3] passdb/pdb_get_set.c:235(pdb_get_group_sid)
>  Primary group for user jholmsta is a UNKNOWN and not a domain group
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 8 of length 76 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 9 of length 184 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\wkssvc -> \PIPE\wkssvc
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \wkssvc
>[2010/01/28 15:56:43,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 10 of length 63 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 11 of length 152 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=64 params=0 setup=2
>[2010/01/28 15:56:43,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:43,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "wkssvc" (pnum 324b)
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 132
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: WKSSVC_NETWKSTAGETINFO
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 51
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 12 of length 45 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12875 (numopen=1)
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 13 of length 104 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 14 of length 76 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 15 of length 184 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\srvsvc -> \PIPE\srvsvc
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \srvsvc
>[2010/01/28 15:56:43,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 16 of length 63 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 17 of length 152 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=64 params=0 setup=2
>[2010/01/28 15:56:43,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:43,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "srvsvc" (pnum 324c)
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 132
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SRVSVC_NETSRVGETINFO
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 40
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 18 of length 45 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12876 (numopen=1)
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 19 of length 104 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 20 of length 76 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 21 of length 184 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\srvsvc -> \PIPE\srvsvc
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \srvsvc
>[2010/01/28 15:56:43,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 22 of length 63 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 23 of length 176 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:43,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:43,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "srvsvc" (pnum 324d)
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 132
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SRVSVC_NETSHAREENUMALL
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 214
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 24 of length 45 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:43,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12877 (numopen=1)
>[2010/01/28 15:56:43,  3] smbd/process.c:1459(process_smb)
>  Transaction 25 of length 92 (0 toread)
>[2010/01/28 15:56:43,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtconX (pid 27216) conn 0x0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/service.c:807(make_connection_snum)
>  Connect path is '/var/spool/samba' for service [Cups-PDF]
>[2010/01/28 15:56:43,  3] smbd/vfs.c:95(vfs_init_default)
>  Initialising default vfs hooks
>[2010/01/28 15:56:43,  3] smbd/vfs.c:129(vfs_init_custom)
>  Initialising custom vfs hooks from [/[Default VFS]/]
>[2010/01/28 15:56:43,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid root does not start with 'S-'.
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  2] passdb/pdb_ldap.c:571(init_sam_from_ldap)
>  init_sam_from_ldap: Entry found for user: root
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  0] passdb/passdb.c:593(lookup_global_sam_name)
>  User root with invalid SID S-1-5-21-2828578859-3132521847-1089271054-500 in passdb
>[2010/01/28 15:56:43,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid @Domain Admins, @Print does not start with 'S-'.
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] lib/util_sid.c:228(string_to_sid)
>  string_to_sid: Sid Operators does not start with 'S-'.
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  1] smbd/service.c:1047(make_connection_snum)
>  api-stp-5pfglb1 (::ffff:172.16.1.142) connect to service Cups-PDF initially as user jholmsta (uid=5560, gid=513) (pid 27216)
>[2010/01/28 15:56:43,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:43,  3] smbd/reply.c:759(reply_tcon_and_X)
>  tconX service=CUPS-PDF 
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 26 of length 106 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 27 of length 106 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 28 of length 76 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 29 of length 106 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:44,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 30 of length 76 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 31 of length 184 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:44,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 32 of length 184 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:44,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 33 of length 184 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:44,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:44,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 34 of length 63 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 35 of length 63 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:44,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:44,  3] smbd/process.c:1459(process_smb)
>  Transaction 36 of length 63 (0 toread)
>[2010/01/28 15:56:44,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 37 of length 300 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 324e)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 38 of length 164 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=76 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3250)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERS
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 5507
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 39 of length 300 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 324f)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 40 of length 45 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12880 (numopen=3)
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 41 of length 45 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12879 (numopen=2)
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 42 of length 106 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 43 of length 184 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:45,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 44 of length 63 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 45 of length 768 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=680 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3251)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERS
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 5507
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 46 of length 45 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12881 (numopen=2)
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 47 of length 344 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=256 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 324e)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_REMOTEFINDFIRSTPRINTERCHANGENOTIFYEX
>[2010/01/28 15:56:45,  3] libsmb/cliconnect.c:2032(cli_start_connection)
>  Connecting to host=API-STP-5PFGLB1.API-DOMAIN
>[2010/01/28 15:56:45,  3] lib/util_sock.c:1033(open_socket_out_send)
>  Connecting to ::ffff:172.16.1.142 at port 445
>[2010/01/28 15:56:45,  2] rpc_server/srv_spoolss_nt.c:2646(spoolss_connect_to_client)
>  spoolss_connect_to_client: unable to open the spoolss pipe on machine API-STP-5PFGLB1.API-DOMAIN. Error was : NT_STATUS_ACCESS_DENIED.
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 48 of length 156 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=68 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 324e)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ROUTERREFRESHPRINTERCHANGENOTIFY
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 394
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 49 of length 106 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 50 of length 106 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:45,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 51 of length 184 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:45,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 52 of length 184 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:45,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 53 of length 63 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 54 of length 63 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 55 of length 300 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3252)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 56 of length 300 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3253)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 57 of length 132 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3252)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:45,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 58 of length 176 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3253)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 59 of length 45 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12882 (numopen=3)
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 60 of length 132 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:45,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:45,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3253)
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:45,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:45,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:45,  3] smbd/process.c:1459(process_smb)
>  Transaction 61 of length 45 (0 toread)
>[2010/01/28 15:56:45,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:45,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12883 (numopen=2)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 62 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 63 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 64 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 65 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 66 of length 4244 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=4156 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/ipc.c(132) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 67 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=3112 max=3112 nread=3112
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 68 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 69 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 70 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 71 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3255)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 72 of length 144 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=56 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3255)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 73 of length 748 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=660 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3255)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 74 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3255)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 75 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 76 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12885 (numopen=4)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 77 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 78 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 79 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 80 of length 148 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 81 of length 148 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 24174
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 82 of length 4348 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=4280
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 83 of length 3136 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=3048 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 24174
>[2010/01/28 15:56:47,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/ipc.c(132) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 84 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=3256 max=3256 nread=3256
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 85 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=4280 max=4280 nread=3032
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 86 of length 176 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 87 of length 2252 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=2164 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDRIVER2
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 88 of length 2196 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 89 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3256)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 90 of length 228 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=140 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 91 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12886 (numopen=3)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 92 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 93 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 94 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 95 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 96 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 97 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 98 of length 300 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3257)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 99 of length 300 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3258)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 100 of length 176 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3258)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 15
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 101 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3257)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 102 of length 176 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3258)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 103 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12887 (numopen=4)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 104 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3258)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 105 of length 4244 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=4156 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 1
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 106 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12888 (numopen=3)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 107 of length 192 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=104 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 108 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 109 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 110 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 111 of length 300 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=212 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3259)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 112 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12889 (numopen=3)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 113 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 114 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 115 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 116 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325a)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 117 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325a)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 118 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 119 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12890 (numopen=4)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 120 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 121 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 122 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325b)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 123 of length 144 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=56 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325b)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 124 of length 748 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=660 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325b)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 125 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325b)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 126 of length 104 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 127 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12891 (numopen=4)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 128 of length 76 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans2 (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 129 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\dssetup -> \PIPE\dssetup
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \lsarpc
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 130 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 131 of length 114 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=26 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "lsarpc" (pnum 325c)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 132
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: DSSETUP_DSROLEGETPRIMARYDOMAININFORMATION
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 48
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 132 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12892 (numopen=3)
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 133 of length 106 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:47,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 134 of length 184 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 135 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 136 of length 320 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=232 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
>  checking name: \\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype)
>  Setting printer type=\\smbtest\Cups-PDF
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 137 of length 148 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 138 of length 148 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=60 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 24174
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 139 of length 4348 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=4280
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 140 of length 3136 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=3048 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 24174
>[2010/01/28 15:56:47,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/ipc.c(132) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 141 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=3256 max=3256 nread=3256
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 142 of length 63 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=4280 max=4280 nread=3032
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 143 of length 176 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=88 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 144 of length 2252 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=2164 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDRIVER2
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 145 of length 2196 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=2108 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 785
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 146 of length 132 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:47,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:47,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325d)
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:47,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:47,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:47,  3] smbd/process.c:1459(process_smb)
>  Transaction 147 of length 45 (0 toread)
>[2010/01/28 15:56:47,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:47,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12893 (numopen=3)
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 148 of length 204 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=116 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 149 of length 224 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=136 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 150 of length 228 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=140 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 151 of length 220 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=132 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 152 of length 236 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=148 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 153 of length 212 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=124 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 154 of length 204 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=116 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 155 of length 224 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=136 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 156 of length 228 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=140 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 157 of length 220 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=132 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 158 of length 236 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=148 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] smbd/process.c:1459(process_smb)
>  Transaction 159 of length 212 (0 toread)
>[2010/01/28 15:56:50,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:50,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=124 params=0 setup=2
>[2010/01/28 15:56:50,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:50,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX
>[2010/01/28 15:56:50,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 160 of length 106 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBntcreateX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:51,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:210(push_sec_ctx)
>  push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:51,  3] smbd/uid.c:428(push_conn_ctx)
>  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2010/01/28 15:56:51,  3] smbd/sec_ctx.c:418(pop_sec_ctx)
>  pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 161 of length 184 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBwriteX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req)
>  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
>[2010/01/28 15:56:51,  3] rpc_server/srv_pipe.c:960(check_bind_req)
>  check_bind_req for \spoolss
>[2010/01/28 15:56:51,  3] smbd/pipes.c:325(pipe_write_andx_done)
>  writeX-IPC nwritten=116
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 162 of length 63 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=1024 max=1024 nread=68
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 163 of length 1236 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=1148 params=0 setup=2
>[2010/01/28 15:56:51,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:51,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 325e)
>[2010/01/28 15:56:51,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 133
>[2010/01/28 15:56:51,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERDRIVERS
>[2010/01/28 15:56:51,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:51,  3] smbd/error.c:60(error_packet_set)
>  error packet at smbd/ipc.c(132) cmd=37 (SMBtrans) STATUS_BUFFER_OVERFLOW
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 164 of length 63 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBreadX (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] smbd/pipes.c:435(pipe_read_andx_done)
>  readX-IPC min=44 max=44 nread=44
>[2010/01/28 15:56:51,  3] smbd/process.c:1459(process_smb)
>  Transaction 165 of length 45 (0 toread)
>[2010/01/28 15:56:51,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:51,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12894 (numopen=3)
>[2010/01/28 15:56:54,  3] smbd/process.c:1459(process_smb)
>  Transaction 166 of length 132 (0 toread)
>[2010/01/28 15:56:54,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtrans (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:54,  3] smbd/ipc.c:536(handle_trans)
>  trans <\PIPE\> data=44 params=0 setup=2
>[2010/01/28 15:56:54,  3] smbd/ipc.c:487(named_pipe)
>  named pipe command on <> name
>[2010/01/28 15:56:54,  3] smbd/ipc.c:451(api_fd_reply)
>  Got API command 0x26 on pipe "spoolss" (pnum 3254)
>[2010/01/28 15:56:54,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:54,  3] rpc_server/srv_pipe.c:2308(api_rpcTNP)
>  api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER
>[2010/01/28 15:56:54,  3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd)
>  Closed policy
>[2010/01/28 15:56:54,  3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context)
>  free_pipe_context: destroying talloc pool of size 0
>[2010/01/28 15:56:54,  3] smbd/process.c:1459(process_smb)
>  Transaction 167 of length 45 (0 toread)
>[2010/01/28 15:56:54,  3] smbd/process.c:1273(switch_message)
>  switch message SMBclose (pid 27216) conn 0x7f2a7c669c30
>[2010/01/28 15:56:54,  3] smbd/reply.c:4488(reply_close)
>  close fd=-1 fnum=12884 (numopen=2)
>[2010/01/28 15:56:54,  3] smbd/process.c:1459(process_smb)
>  Transaction 168 of length 39 (0 toread)
>[2010/01/28 15:56:54,  3] smbd/process.c:1273(switch_message)
>  switch message SMBtdis (pid 27216) conn 0x7f2a7c673aa0
>[2010/01/28 15:56:54,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:54,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2010/01/28 15:56:54,  1] smbd/service.c:1226(close_cnum)
>  api-stp-5pfglb1 (::ffff:172.16.1.142) closed connection to service Cups-PDF
>[2010/01/28 15:56:54,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to Cups-PDF
>[2010/01/28 15:56:54,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0

Actions: View

Attachments on bug 7074: 5238 | 5239 | 5240 | 5241