[Samba] Isolated printer problem

Misty Stanley-Jones misty at borkholder.com
Tue Jan 4 22:41:55 GMT 2005 

On Tuesday 04 January 2005 16:54, John H Terpstra wrote:

>
> Is the 10th user a manager of the "printer operators" group?
No.  Nobody is.

>
> At "log level = 5" what do the logs for that 10th machine show has
> happened. I suspect you will find your answer there.
>
> 	log level = 5
> 	max log size = 0
> 	log file = /var/log/samba/%m
>
> Note: %m gets expanded to the name of the workstation.

Of note -- It turns out through testing (rather than taking the user's word 
for it) that he is not able to see any spooled jobs on any network printer of 
his.

Nothing odd that I can see here except that the message gets sent to the 
plotter 8 times ... maybe that is normal... Spewed here:

[2005/01/04 17:21:11, 5] 
printing/notify.c:print_notify_send_messages_to_printer(169)
  print_notify_send_messages_to_printer: sending 8 print notify messages to 
printer plotter
[2005/01/04 17:21:46, 3] smbd/process.c:process_smb(1092)
  Transaction 71331 of length 39
[2005/01/04 17:21:46, 5] lib/util.c:show_msg(461)
[2005/01/04 17:21:46, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=2
  smb_pid=65279
  smb_uid=129
  smb_mid=14665
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:21:46, 3] smbd/process.c:switch_message(887)
  switch message SMBtdis (pid 25406) conn 0x83dc9c8
[2005/01/04 17:21:46, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:21:46, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:21:46, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /data/samba/netlogon
[2005/01/04 17:21:46, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:21:46, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:21:46, 1] smbd/service.c:close_cnum(836)
  w2kgreg (192.168.1.57) closed connection to service netlogon
[2005/01/04 17:21:46, 3] smbd/connection.c:yield_connection(69)
  Yielding connection to netlogon
[2005/01/04 17:21:46, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /
[2005/01/04 17:21:46, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:21:46, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:21:46, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:21:46, 5] lib/util.c:show_msg(461)
[2005/01/04 17:21:46, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=2
  smb_pid=65279
  smb_uid=129
  smb_mid=14665
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:process_smb(1092)
  Transaction 71332 of length 39
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=163
  smb_mid=14729
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:switch_message(887)
  switch message SMBtdis (pid 25406) conn 0x840fa48
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /tmp
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 3] smbd/service.c:close_cnum(836)
  w2kgreg (192.168.1.57) closed connection to service IPC$
[2005/01/04 17:22:20, 3] smbd/connection.c:yield_connection(69)
  Yielding connection to IPC$
[2005/01/04 17:22:20, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=65279
  smb_uid=163
  smb_mid=14729
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:process_smb(1092)
  Transaction 71333 of length 43
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=39
  smb_com=0x74
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=163
  smb_mid=14793
  smt_wct=2
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:switch_message(887)
  switch message SMBulogoffX (pid 25406) conn 0x0
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 5] auth/auth_util.c:free_server_info(1344)
  attempting to free (and zero) a server_info structure
[2005/01/04 17:22:20, 3] smbd/reply.c:reply_ulogoffX(1249)
  ulogoffX vuid=163
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=39
  smb_com=0x74
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=0
  smb_pid=65279
  smb_uid=163
  smb_mid=14793
  smt_wct=2
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:process_smb(1092)
  Transaction 71334 of length 39
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=3
  smb_pid=65279
  smb_uid=129
  smb_mid=14857
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:22:20, 3] smbd/process.c:switch_message(887)
  switch message SMBtdis (pid 25406) conn 0x8408848
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /data/samba/spool
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 1] smbd/service.c:close_cnum(836)
  w2kgreg (192.168.1.57) closed connection to service plotter
[2005/01/04 17:22:20, 3] smbd/connection.c:yield_connection(69)
  Yielding connection to plotter
[2005/01/04 17:22:20, 4] smbd/vfs.c:vfs_ChDir(654)
  vfs_ChDir to /
[2005/01/04 17:22:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:22:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:22:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(461)
[2005/01/04 17:22:20, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x71
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=3
  smb_pid=65279
  smb_uid=129
  smb_mid=14857
  smt_wct=0
  smb_bcc=0
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:23:20, 5] smbd/uid.c:change_to_root_user(296)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2005/01/04 17:23:20, 5] lib/smbldap.c:smbldap_close(930)
  The connection to the LDAP server was closed
[2005/01/04 17:23:20, 3] smbd/process.c:check_reload(1316)
  Printcap cache time expired.
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("acct_hp8500")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("plotter")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("engr_hp1300")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("engr_legacy_printer")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("FAX")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("PDFcreator")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("truss_hp4")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("truss_hp4050")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printername_ok(308)
  cups_printername_ok("truss_hp5n")
[2005/01/04 17:23:20, 5] printing/print_cups.c:cups_printer_fn(79)
  cups_printer_fn(0x807c601)
[2005/01/04 17:23:20, 3] printing/printing.c:print_cache_expired(1930)
  print cache expired for queue truss_hp4050 (last_qscan_time = 1104877271, 
time now =1104877400, qcachetime = 10)
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] printing/printing.c:print_cache_expired(1930)
  print cache expired for queue truss_hp4 (last_qscan_time = 1104877271, time 
now = 1104877400, qcachetime = 10)
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] printing/printing.c:print_cache_expired(1930)
  print cache expired for queue engr_hp1300 (last_qscan_time = 1104877271, 
time now = 1104877400, qcachetime = 10)
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] printing/printing.c:print_cache_expired(1930)
  print cache expired for queue truss_hp5n (last_qscan_time = 1104877271, time 
now = 1104877400, qcachetime = 10)
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:push_sec_ctx(256)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 3] smbd/uid.c:push_conn_ctx(365)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:set_sec_ctx(288)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_nt_user_token(486)
  NT user token: (NULL)
[2005/01/04 17:23:20, 5] auth/auth_util.c:debug_unix_user_token(505)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2005/01/04 17:23:20, 3] smbd/sec_ctx.c:pop_sec_ctx(386)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0


>
> - John T.
> --
> John H Terpstra
> Samba-Team Member
> Phone: +1 (650) 580-8668
>
> Author:
> The Official Samba-3 HOWTO & Reference Guide, ISBN: 0131453556
> Samba-3 by Example, ISBN: 0131472216
> Hardening Linux, ISBN: 0072254971
> Other books in production.

More information about the samba mailing list