[2010/01/28 15:57:09, 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:57:09, 3] auth/auth.c:225(check_ntlm_password) check_ntlm_password: mapped user is: [SMBTEST]\[jholmsta]@[API-STP-5PFGLB1] [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 2] lib/smbldap.c:856(smbldap_open_connection) smbldap_open_connection: connection opened [2010/01/28 15:57:09, 3] lib/smbldap.c:1067(smbldap_connect_system) ldap_connect_system: successful connection to the LDAP server [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:571(init_sam_from_ldap) init_sam_from_ldap: Entry found for user: jholmsta [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 513 [2010/01/28 15:57:09, 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:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 513 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 512 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 1501 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 1510 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 1511 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 2576 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:09, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 1800 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] auth/auth.c:271(check_ntlm_password) check_ntlm_password: sam authentication for user [jholmsta] succeeded [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 2] auth/auth.c:310(check_ntlm_password) check_ntlm_password: authentication for user [jholmsta] -> [jholmsta] -> [jholmsta] succeeded [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] auth/token_util.c:464(create_local_nt_token) Failed to fetch domain sid for API-DOMAIN [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:09, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:09, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 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:57:10, 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:57:10, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-2] [2010/01/28 15:57:10, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-11] [2010/01/28 15:57:10, 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:57:10, 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:57:10, 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:57:10, 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:57:10, 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:57:10, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-2804] [2010/01/28 15:57:10, 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:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] libsmb/ntlmssp_sign.c:342(ntlmssp_sign_init) NTLMSSP Sign/Seal - Initialising with flags: [2010/01/28 15:57:10, 3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0xe2088215 [2010/01/28 15:57:10, 3] smbd/password.c:269(register_existing_vuid) register_existing_vuid: User name: jholmsta Real name: John Holmstadt [2010/01/28 15:57:10, 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:57:10, 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:57:10, 3] smbd/process.c:1459(process_smb) Transaction 3 of length 94 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtconX (pid 27218) conn 0x0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/service.c:807(make_connection_snum) Connect path is '/tmp' for service [IPC$] [2010/01/28 15:57:10, 3] smbd/vfs.c:95(vfs_init_default) Initialising default vfs hooks [2010/01/28 15:57:10, 3] smbd/vfs.c:129(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 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 27218) [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/reply.c:759(reply_tcon_and_X) tconX service=IPC$ [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 4 of length 106 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/service.c:328(find_service) checking for home directory . gave (NULL) [2010/01/28 15:57:10, 3] smbd/service.c:414(find_service) find_service() failed to find service . [2010/01/28 15:57:10, 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:57:10, 3] smbd/process.c:1459(process_smb) Transaction 5 of length 88 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtconX (pid 27218) conn 0x0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/service.c:328(find_service) checking for home directory . gave (NULL) [2010/01/28 15:57:10, 3] smbd/service.c:414(find_service) find_service() failed to find service . [2010/01/28 15:57:10, 0] smbd/service.c:1188(make_connection) api-stp-5pfglb1 (::ffff:172.16.1.142) couldn't find service . [2010/01/28 15:57:10, 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:57:10, 3] smbd/process.c:1459(process_smb) Transaction 6 of length 88 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtconX (pid 27218) conn 0x0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/service.c:328(find_service) checking for home directory . gave (NULL) [2010/01/28 15:57:10, 3] smbd/service.c:414(find_service) find_service() failed to find service . [2010/01/28 15:57:10, 0] smbd/service.c:1188(make_connection) api-stp-5pfglb1 (::ffff:172.16.1.142) couldn't find service . [2010/01/28 15:57:10, 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:57:10, 3] smbd/process.c:1459(process_smb) Transaction 7 of length 104 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 2] passdb/pdb_ldap.c:2353(init_group_from_ldap) init_group_from_ldap: Entry found for group: 513 [2010/01/28 15:57:10, 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:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 8 of length 76 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 9 of length 184 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\wkssvc -> \PIPE\wkssvc [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \wkssvc [2010/01/28 15:57:10, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 10 of length 63 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 11 of length 164 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=76 params=0 setup=2 [2010/01/28 15:57:10, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:10, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "wkssvc" (pnum 3264) [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 132 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: WKSSVC_NETWKSTAGETINFO [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 51 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 12 of length 45 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12900 (numopen=1) [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 13 of length 104 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 14 of length 76 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 15 of length 184 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\srvsvc -> \PIPE\srvsvc [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \srvsvc [2010/01/28 15:57:10, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 16 of length 63 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 17 of length 164 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=76 params=0 setup=2 [2010/01/28 15:57:10, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:10, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "srvsvc" (pnum 3265) [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 132 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SRVSVC_NETSRVGETINFO [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 40 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 18 of length 45 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12901 (numopen=1) [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 19 of length 104 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 20 of length 76 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 21 of length 184 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\srvsvc -> \PIPE\srvsvc [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \srvsvc [2010/01/28 15:57:10, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 22 of length 63 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 23 of length 188 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=100 params=0 setup=2 [2010/01/28 15:57:10, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:10, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "srvsvc" (pnum 3266) [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 132 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SRVSVC_NETSHAREENUMALL [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 214 [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 24 of length 45 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:10, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12902 (numopen=1) [2010/01/28 15:57:10, 3] smbd/process.c:1459(process_smb) Transaction 25 of length 102 (0 toread) [2010/01/28 15:57:10, 3] smbd/process.c:1273(switch_message) switch message SMBtconX (pid 27218) conn 0x0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/service.c:807(make_connection_snum) Connect path is '/var/spool/samba' for service [Cups-PDF] [2010/01/28 15:57:10, 3] smbd/vfs.c:95(vfs_init_default) Initialising default vfs hooks [2010/01/28 15:57:10, 3] smbd/vfs.c:129(vfs_init_custom) Initialising custom vfs hooks from [/[Default VFS]/] [2010/01/28 15:57:10, 3] lib/util_sid.c:228(string_to_sid) string_to_sid: Sid root does not start with 'S-'. [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 2] passdb/pdb_ldap.c:571(init_sam_from_ldap) init_sam_from_ldap: Entry found for user: root [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 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:57:10, 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:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] lib/util_sid.c:228(string_to_sid) string_to_sid: Sid Operators does not start with 'S-'. [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 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 27218) [2010/01/28 15:57:10, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:10, 3] smbd/reply.c:759(reply_tcon_and_X) tconX service=CUPS-PDF [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 26 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 27 of length 76 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 28 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 29 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 30 of length 312 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3267) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 31 of length 168 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=80 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3267) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 1024 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 32 of length 132 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3267) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:12, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 33 of length 45 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12903 (numopen=1) [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 34 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 35 of length 76 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 36 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 37 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 38 of length 312 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3268) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 39 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 40 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 41 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 42 of length 312 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3269) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 43 of length 45 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12905 (numopen=2) [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 44 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 45 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 46 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 47 of length 176 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326a) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERS [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 5512 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 48 of length 45 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12906 (numopen=2) [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 49 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 50 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 51 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 52 of length 780 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=692 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326b) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERS [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 5512 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 53 of length 45 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12907 (numopen=2) [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 54 of length 344 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=256 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3268) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_REMOTEFINDFIRSTPRINTERCHANGENOTIFYEX [2010/01/28 15:57:12, 3] libsmb/cliconnect.c:2032(cli_start_connection) Connecting to host=API-STP-5PFGLB1.API-DOMAIN [2010/01/28 15:57:12, 3] lib/util_sock.c:1033(open_socket_out_send) Connecting to ::ffff:172.16.1.142 at port 445 [2010/01/28 15:57:12, 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:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 55 of length 156 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=68 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3268) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ROUTERREFRESHPRINTERCHANGENOTIFY [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 399 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 56 of length 106 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:12, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 57 of length 184 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:12, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 58 of length 63 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 59 of length 312 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326c) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 60 of length 176 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326c) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 61 of length 132 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:12, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:12, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326c) [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:12, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:12, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:12, 3] smbd/process.c:1459(process_smb) Transaction 62 of length 45 (0 toread) [2010/01/28 15:57:12, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:12, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12908 (numopen=2) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 63 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 64 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 65 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 66 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 67 of length 4244 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=4156 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 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:57:14, 3] smbd/process.c:1459(process_smb) Transaction 68 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=3112 max=3112 nread=3112 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 69 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 70 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 71 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 72 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326e) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 73 of length 144 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=56 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326e) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 74 of length 768 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=680 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326e) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 75 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326e) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 76 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 77 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12910 (numopen=4) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 78 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 79 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 80 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 81 of length 148 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=60 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 82 of length 148 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=60 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 24174 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 83 of length 4348 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=4280 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 84 of length 3136 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=3048 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 24174 [2010/01/28 15:57:14, 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:57:14, 3] smbd/process.c:1459(process_smb) Transaction 85 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=3256 max=3256 nread=3256 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 86 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=4280 max=4280 nread=3032 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 87 of length 176 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 88 of length 2252 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=2164 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDRIVER2 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 89 of length 2196 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=2108 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 90 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326f) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 91 of length 228 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=140 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 92 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12911 (numopen=3) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 93 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 94 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 95 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 96 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 97 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 98 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 99 of length 312 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3270) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 100 of length 312 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3271) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 101 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3270) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 102 of length 176 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3271) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 15 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 103 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12912 (numopen=4) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 104 of length 176 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3271) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 105 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3271) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 106 of length 4244 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=4156 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 1 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 107 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12913 (numopen=3) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 108 of length 192 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=104 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 109 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 110 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 111 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 112 of length 312 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=224 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3272) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197 [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 113 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12914 (numopen=3) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 114 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 115 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 116 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 117 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3273) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 118 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3273) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 119 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 120 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12915 (numopen=4) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 121 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 122 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 123 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3274) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 124 of length 144 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=56 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3274) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 125 of length 768 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=680 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3274) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 126 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3274) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 127 of length 104 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 128 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12916 (numopen=4) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 129 of length 76 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans2 (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 130 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\dssetup -> \PIPE\dssetup [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \lsarpc [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 131 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 132 of length 114 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=26 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "lsarpc" (pnum 3275) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 132 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: DSSETUP_DSROLEGETPRIMARYDOMAININFORMATION [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 48 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 133 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12917 (numopen=3) [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 134 of length 106 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:14, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 135 of length 184 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 136 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 137 of length 328 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=240 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX checking name: \\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_spoolss_nt.c:394(set_printer_hnd_printertype) Setting printer type=\\172.16.1.197\Cups-PDF [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 138 of length 148 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=60 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 139 of length 148 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=60 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 24174 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 140 of length 4348 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=4280 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 141 of length 3136 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=3048 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMFORMS [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 24174 [2010/01/28 15:57:14, 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:57:14, 3] smbd/process.c:1459(process_smb) Transaction 142 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=3256 max=3256 nread=3256 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 143 of length 63 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=4280 max=4280 nread=3032 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 144 of length 176 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=88 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 145 of length 2252 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=2164 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDRIVER2 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 146 of length 2196 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=2108 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 795 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 147 of length 132 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:14, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:14, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3276) [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:14, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:14, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:14, 3] smbd/process.c:1459(process_smb) Transaction 148 of length 45 (0 toread) [2010/01/28 15:57:14, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:14, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12918 (numopen=3) [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 149 of length 204 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=116 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 150 of length 224 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=136 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 151 of length 228 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=140 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 152 of length 220 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=132 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 153 of length 236 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=148 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 154 of length 212 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=124 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 155 of length 204 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=116 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 156 of length 224 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=136 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 157 of length 228 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=140 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 158 of length 220 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=132 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 159 of length 236 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=148 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] smbd/process.c:1459(process_smb) Transaction 160 of length 212 (0 toread) [2010/01/28 15:57:15, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:15, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=124 params=0 setup=2 [2010/01/28 15:57:15, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:15, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:15, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATAEX [2010/01/28 15:57:15, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:16, 3] smbd/process.c:1459(process_smb) Transaction 161 of length 106 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBntcreateX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:16, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(5560, 513) : sec_ctx_stack_ndx = 1 [2010/01/28 15:57:16, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(100) : conn_ctx_stack_ndx = 0 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2010/01/28 15:57:16, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (5560, 513) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:16, 3] smbd/process.c:1459(process_smb) Transaction 162 of length 184 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBwriteX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] rpc_server/srv_pipe.c:1584(api_pipe_bind_req) api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss [2010/01/28 15:57:16, 3] rpc_server/srv_pipe.c:960(check_bind_req) check_bind_req for \spoolss [2010/01/28 15:57:16, 3] smbd/pipes.c:325(pipe_write_andx_done) writeX-IPC nwritten=116 [2010/01/28 15:57:16, 3] smbd/process.c:1459(process_smb) Transaction 163 of length 63 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=1024 max=1024 nread=68 [2010/01/28 15:57:16, 3] smbd/process.c:1459(process_smb) Transaction 164 of length 1248 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=1160 params=0 setup=2 [2010/01/28 15:57:16, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:16, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 3277) [2010/01/28 15:57:16, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 133 [2010/01/28 15:57:16, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_ENUMPRINTERDRIVERS [2010/01/28 15:57:16, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:16, 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:57:16, 3] smbd/process.c:1459(process_smb) Transaction 165 of length 63 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBreadX (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] smbd/pipes.c:435(pipe_read_andx_done) readX-IPC min=44 max=44 nread=44 [2010/01/28 15:57:16, 3] smbd/process.c:1459(process_smb) Transaction 166 of length 45 (0 toread) [2010/01/28 15:57:16, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:16, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12919 (numopen=3) [2010/01/28 15:57:24, 3] smbd/process.c:1459(process_smb) Transaction 167 of length 132 (0 toread) [2010/01/28 15:57:24, 3] smbd/process.c:1273(switch_message) switch message SMBtrans (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:24, 3] smbd/ipc.c:536(handle_trans) trans <\PIPE\> data=44 params=0 setup=2 [2010/01/28 15:57:24, 3] smbd/ipc.c:487(named_pipe) named pipe command on <> name [2010/01/28 15:57:24, 3] smbd/ipc.c:451(api_fd_reply) Got API command 0x26 on pipe "spoolss" (pnum 326d) [2010/01/28 15:57:24, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:24, 3] rpc_server/srv_pipe.c:2308(api_rpcTNP) api_rpcTNP: rpc command: SPOOLSS_CLOSEPRINTER [2010/01/28 15:57:24, 3] rpc_server/srv_lsa_hnd.c:218(close_policy_hnd) Closed policy [2010/01/28 15:57:24, 3] rpc_server/srv_pipe_hnd.c:343(free_pipe_context) free_pipe_context: destroying talloc pool of size 0 [2010/01/28 15:57:24, 3] smbd/process.c:1459(process_smb) Transaction 168 of length 45 (0 toread) [2010/01/28 15:57:24, 3] smbd/process.c:1273(switch_message) switch message SMBclose (pid 27218) conn 0x7f2a7c669c30 [2010/01/28 15:57:24, 3] smbd/reply.c:4488(reply_close) close fd=-1 fnum=12909 (numopen=2) [2010/01/28 15:57:24, 3] smbd/process.c:1459(process_smb) Transaction 169 of length 39 (0 toread) [2010/01/28 15:57:24, 3] smbd/process.c:1273(switch_message) switch message SMBtdis (pid 27218) conn 0x7f2a7c673aa0 [2010/01/28 15:57:24, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:24, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2010/01/28 15:57:24, 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:57:24, 3] smbd/connection.c:31(yield_connection) Yielding connection to Cups-PDF [2010/01/28 15:57:24, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0