Bug 2851 - Built-in Administrator account has priveleges to access printers of untrusted domains
Built-in Administrator account has priveleges to access printers of untrusted...
Status: RESOLVED INVALID
Product: Samba 3.0
Classification: Unclassified
Component: Printing
3.0.13
All Windows 2000
: P3 normal
: none
Assigned To: Gerald (Jerry) Carter
Samba QA Contact
:
: 2852 (view as bug list)
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2005-07-06 04:48 UTC by Guruswamy
Modified: 2005-09-27 09:28 UTC (History)
0 users

See Also:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Guruswamy 2005-07-06 04:48:03 UTC
Built-in Microsoft user Administrator is able to acess print server with
priveleged authorities. That include administrators from domains having no 
trust with the one where print server belongs to and even local PC administrator
user.
Comment 1 Gerald (Jerry) Carter 2005-07-06 05:56:57 UTC
Are you really using version 3.0.1?  If so, could you please try 
to reproduce this against a more recent release such as 3.0.14a?
Are you running with drivers installed on the Samba host?
We'll need a lot more details here.
Comment 2 Gerald (Jerry) Carter 2005-07-06 07:16:45 UTC
*** Bug 2852 has been marked as a duplicate of this bug. ***
Comment 3 Guruswamy 2005-07-08 01:41:07 UTC
The version of samba being used is 3.0.13.

The drivers are installed in the Samba host. The user is able to choose between
the drivers already installed. However, the "New Driver" button of Add printer
wizard is disabled. 

The account used to create the printer is part of a group configured as cups
admin group in samba server.
Comment 4 Gerald (Jerry) Carter 2005-07-15 09:47:36 UTC
I need to see a lvel 10 debug log of the user from the trusted 
domain getting administartive access to the printer.  I also 
need to see your smb.conf.  

Also, are you running winbindd on the samba box?

I don't think this is a bug, but rather a misconfiguration
where the trusted user named Administrator is getting mapped
to a unix user by the same name.
Comment 5 Guruswamy 2005-07-19 06:15:51 UTC
(In reply to comment #4)
> I need to see a lvel 10 debug log of the user from the trusted 
> domain getting administartive access to the printer.  I also 
> need to see your smb.conf.  
Level 10 logs
====================================
[2005/07/19 13:16:09, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:09, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:09, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:09, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:09, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:12, 10] nsswitch/winbindd.c:winbind_client_read(470)
  client_read: read 1824 bytes. Need 0 more for a full request.
[2005/07/19 13:16:12, 10] nsswitch/winbindd.c:process_request(321)
  process_request: request fn GETPWUID
[2005/07/19 13:16:12, 3] nsswitch/winbindd_user.c:winbindd_getpwuid(224)
  [ 9809]: getpwuid 70002
[2005/07/19 13:16:12, 4] nsswitch/winbindd_acct.c:wb_getpwuid(414)
  wb_getpwuid: failed to locate uid == 70002
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_uid_to_sid(108)
  idmap_uid_to_sid: uid = [70002]
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:db_get_sid_from_id(283)
  db_get_sid_from_id: id_type_in = 0x1
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record UID 70002
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record UID 70002 ->
S-1-5-21-606747145-630328440-682003330-500
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-500 of type 0x1
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-500 ->
UID 70002
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(241)
  internal_get_id_from_sid: ID_USERID fetching record
S-1-5-21-606747145-630328440-682003330-500 -> UID 70002 
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(356)
  refresh_sequence_number: DOMAIN2000 time ok
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(386)
  refresh_sequence_number: DOMAIN2000 seq number is now 14555
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:centry_expired(410)
  centry_expired: Key SN/S-1-5-21-606747145-630328440-682003330-500 for domain
DOMAIN2000 is good.
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:wcache_fetch(489)
  wcache_fetch: returning entry SN/S-1-5-21-606747145-630328440-682003330-500
for domain DOMAIN2000
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:sid_to_name(1023)
  sid_to_name: [Cached] - cached name for domain DOMAIN2000 status Success
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(356)
  refresh_sequence_number: DOMAIN2000 time ok
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(386)
  refresh_sequence_number: DOMAIN2000 seq number is now 14555
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:centry_expired(410)
  centry_expired: Key U/S-1-5-21-606747145-630328440-682003330-500 for domain
DOMAIN2000 is good.
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:wcache_fetch(489)
  wcache_fetch: returning entry U/S-1-5-21-606747145-630328440-682003330-500 for
domain DOMAIN2000
[2005/07/19 13:16:12, 10] nsswitch/winbindd_cache.c:query_user(1096)
  query_user: [Cached] - cached info for domain DOMAIN2000 status Success
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_gid(179)
  sid_to_gid: sid = [S-1-5-21-606747145-630328440-682003330-513]
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-513 of type 0x2
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-513 ->
GID 70001
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(260)
  internal_get_id_from_sid: ID_GROUPID fetching record
S-1-5-21-606747145-630328440-682003330-513 -> GID 70001 
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record GID 70001
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record GID 70001 ->
S-1-5-21-606747145-630328440-682003330-513
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_gid(187)
  idmap_sid_to_gid: gid = [70001]
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_uid(150)
  idmap_sid_to_uid: sid = [S-1-5-21-606747145-630328440-682003330-500]
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-500 of type 0x1
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-500 ->
UID 70002
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(241)
  internal_get_id_from_sid: ID_USERID fetching record
S-1-5-21-606747145-630328440-682003330-500 -> UID 70002 
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record UID 70002
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record UID 70002 ->
S-1-5-21-606747145-630328440-682003330-500
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_uid(157)
  idmap_sid_to_uid: uid = [70002]
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_gid(179)
  sid_to_gid: sid = [S-1-5-21-606747145-630328440-682003330-513]
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-513 of type 0x2
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-513 ->
GID 70001
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_id_from_sid(260)
  internal_get_id_from_sid: ID_GROUPID fetching record
S-1-5-21-606747145-630328440-682003330-513 -> GID 70001 
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record GID 70001
[2005/07/19 13:16:12, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record GID 70001 ->
S-1-5-21-606747145-630328440-682003330-513
[2005/07/19 13:16:12, 10] sam/idmap_util.c:idmap_sid_to_gid(187)
  idmap_sid_to_gid: gid = [70001]
[2005/07/19 13:16:12, 10] nsswitch/winbindd.c:client_write(524)
  client_write: wrote 1300 bytes.
[2005/07/19 13:16:12, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:12, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:12, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:12, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:12, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:12, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778969, time now = 1121778972, qcachetime = 0)
[2005/07/19 13:16:12, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:12, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:12, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:12, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:13, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:13, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:13, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:13, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:13, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:13, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:13, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:13, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:13, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778973, time now = 1121778973, qcachetime = 0)
[2005/07/19 13:16:13, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:13, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:13, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:13, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:13, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:13, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:13, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:17, 10] nsswitch/winbindd.c:winbind_client_read(470)
  client_read: read 1824 bytes. Need 0 more for a full request.
[2005/07/19 13:16:17, 10] nsswitch/winbindd.c:process_request(321)
  process_request: request fn GETPWUID
[2005/07/19 13:16:17, 3] nsswitch/winbindd_user.c:winbindd_getpwuid(224)
  [ 9809]: getpwuid 70002
[2005/07/19 13:16:17, 4] nsswitch/winbindd_acct.c:wb_getpwuid(414)
  wb_getpwuid: failed to locate uid == 70002
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_uid_to_sid(108)
  idmap_uid_to_sid: uid = [70002]
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:db_get_sid_from_id(283)
  db_get_sid_from_id: id_type_in = 0x1
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record UID 70002
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record UID 70002 ->
S-1-5-21-606747145-630328440-682003330-500
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-500 of type 0x1
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-500 ->
UID 70002
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(241)
  internal_get_id_from_sid: ID_USERID fetching record
S-1-5-21-606747145-630328440-682003330-500 -> UID 70002 
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(356)
  refresh_sequence_number: DOMAIN2000 time ok
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(386)
  refresh_sequence_number: DOMAIN2000 seq number is now 14555
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:centry_expired(410)
  centry_expired: Key SN/S-1-5-21-606747145-630328440-682003330-500 for domain
DOMAIN2000 is good.
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:wcache_fetch(489)
  wcache_fetch: returning entry SN/S-1-5-21-606747145-630328440-682003330-500
for domain DOMAIN2000
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:sid_to_name(1023)
  sid_to_name: [Cached] - cached name for domain DOMAIN2000 status Success
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(356)
  refresh_sequence_number: DOMAIN2000 time ok
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:refresh_sequence_number(386)
  refresh_sequence_number: DOMAIN2000 seq number is now 14555
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:centry_expired(410)
  centry_expired: Key U/S-1-5-21-606747145-630328440-682003330-500 for domain
DOMAIN2000 is good.
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:wcache_fetch(489)
  wcache_fetch: returning entry U/S-1-5-21-606747145-630328440-682003330-500 for
domain DOMAIN2000
[2005/07/19 13:16:17, 10] nsswitch/winbindd_cache.c:query_user(1096)
  query_user: [Cached] - cached info for domain DOMAIN2000 status Success
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_gid(179)
  sid_to_gid: sid = [S-1-5-21-606747145-630328440-682003330-513]
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-513 of type 0x2
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-513 ->
GID 70001
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(260)
  internal_get_id_from_sid: ID_GROUPID fetching record
S-1-5-21-606747145-630328440-682003330-513 -> GID 70001 
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record GID 70001
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record GID 70001 ->
S-1-5-21-606747145-630328440-682003330-513
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_gid(187)
  idmap_sid_to_gid: gid = [70001]
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_uid(150)
  idmap_sid_to_uid: sid = [S-1-5-21-606747145-630328440-682003330-500]
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-500 of type 0x1
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-500 ->
UID 70002
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(241)
  internal_get_id_from_sid: ID_USERID fetching record
S-1-5-21-606747145-630328440-682003330-500 -> UID 70002 
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record UID 70002
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record UID 70002 ->
S-1-5-21-606747145-630328440-682003330-500
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_uid(157)
  idmap_sid_to_uid: uid = [70002]
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_gid(179)
  sid_to_gid: sid = [S-1-5-21-606747145-630328440-682003330-513]
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:db_get_id_from_sid(315)
  db_get_id_from_sid
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-513 of type 0x2
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-513 ->
GID 70001
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_id_from_sid(260)
  internal_get_id_from_sid: ID_GROUPID fetching record
S-1-5-21-606747145-630328440-682003330-513 -> GID 70001 
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record GID 70001
[2005/07/19 13:16:17, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record GID 70001 ->
S-1-5-21-606747145-630328440-682003330-513
[2005/07/19 13:16:17, 10] sam/idmap_util.c:idmap_sid_to_gid(187)
  idmap_sid_to_gid: gid = [70001]
[2005/07/19 13:16:17, 10] nsswitch/winbindd.c:client_write(524)
  client_write: wrote 1300 bytes.
[2005/07/19 13:16:17, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:17, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:17, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:17, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:17, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:17, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778973, time now = 1121778977, qcachetime = 0)
[2005/07/19 13:16:17, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:17, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:17, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:17, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:18, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:18, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:18, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:18, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:18, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:18, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:18, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:18, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:18, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778978, time now = 1121778978, qcachetime = 0)
[2005/07/19 13:16:18, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:18, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:18, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:18, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:18, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:18, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:18, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:22, 10] nsswitch/winbindd.c:winbind_client_read(470)
  client_read: read 1824 bytes. Need 0 more for a full request.
[2005/07/19 13:16:22, 10] nsswitch/winbindd.c:process_request(321)
  process_request: request fn GETPWUID
[2005/07/19 13:16:22, 3] nsswitch/winbindd_user.c:winbindd_getpwuid(224)
  [ 9809]: getpwuid 70002
[2005/07/19 13:16:22, 4] nsswitch/winbindd_acct.c:wb_getpwuid(414)
  wb_getpwuid: failed to locate uid == 70002
[2005/07/19 13:16:22, 10] sam/idmap_util.c:idmap_uid_to_sid(108)
  idmap_uid_to_sid: uid = [70002]
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:db_get_sid_from_id(283)
  db_get_sid_from_id: id_type_in = 0x1
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:internal_get_sid_from_id(190)
  internal_get_sid_from_id: fetching record UID 70002
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:internal_get_sid_from_id(196)
  internal_get_sid_from_id: fetching record UID 70002 ->
S-1-5-21-606747145-630328440-682003330-500
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:internal_get_id_from_sid(221)
  internal_get_id_from_sid: fetching record
S-1-5-21-606747145-630328440-682003330-500 of type 0x1
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:internal_get_id_from_sid(228)
  internal_get_id_from_sid: record S-1-5-21-606747145-630328440-682003330-500 ->
UID 70002
[2005/07/19 13:16:22, 10] sam/idmap_tdb.c:internal_get_id_from_sid(241)
  internal_get_id_from_sid: ID_USERID fetching record
S-1-5-21-606747145-630328440-682003330-500 -> UID 70002 
[2005/07/19 13:16:22, 10] nsswitch/winbindd_cache.c:fetch_cache_seqnum(286)
  fetch_cache_seqnum: timeout [DOMAIN2000][14555 @ 1121778968]
[2005/07/19 13:16:22, 10] nsswitch/winbindd_rpc.c:sequence_number(878)
  rpc: fetch sequence_number for DOMAIN2000
[2005/07/19 13:16:22, 10] rpc_client/cli_samr.c:cli_samr_open_domain(186)
  cli_samr_open_domain with sid S-1-5-21-606747145-630328440-682003330
[2005/07/19 13:16:22, 5] rpc_parse/parse_samr.c:init_samr_q_open_domain(250)
  samr_init_samr_q_open_domain
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 samr_io_q_open_domain 
[2005/07/19 13:16:22, 6] rpc_parse/parse_prs.c:prs_debug(82)
      000000 smb_io_pol_hnd pol
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0000 data1: 00000000
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0004 data2: 6e36647c
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          0008 data3: 9b54
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          000a data4: 4695
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
          000c data5: a9 26 95 05 fa 64 7e 5b 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      0014 flags: 02000000
[2005/07/19 13:16:22, 6] rpc_parse/parse_prs.c:prs_debug(82)
      000018 smb_io_dom_sid2 sid
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0018 num_auths: 00000004
[2005/07/19 13:16:22, 7] rpc_parse/parse_prs.c:prs_debug(82)
          00001c smb_io_dom_sid sid
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              001c sid_rev_num: 01
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              001d num_auths  : 04
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              001e id_auth[0] : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              001f id_auth[1] : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              0020 id_auth[2] : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              0021 id_auth[3] : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              0022 id_auth[4] : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
              0023 id_auth[5] : 05
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32s(869)
              0024 sub_auths : 00000015 242a3a09 25920c78 28a68b82 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000038 smb_io_rpc_hdr_auth hdr_auth
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0038 auth_type    : 44
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0039 auth_level   : 05
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      003a padding      : 04
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      003b reserved     : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      003c auth_context : 00000001
[2005/07/19 13:16:22, 10] rpc_client/cli_pipe.c:rpc_api_pipe_req(1034)
  SCHANNEL seq_num=4232
[2005/07/19 13:16:22, 10] rpc_parse/parse_prs.c:netsec_encode(1481)
  SCHANNEL: netsec_encode seq_num=4232 data_len=56
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000040 smb_io_rpc_auth_netsec_chk 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0040 sig  : 77 00 ff ff ff ff 00 00 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0048 seq_num: 61 0d cb ca 30 25 2a 00 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0050 packet_digest: 14 05 4b 43 d0 d7 96 f3 
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0058 confounder: 62 2d 86 e4 bb 48 e5 ad 
[2005/07/19 13:16:22, 5] rpc_client/cli_pipe.c:create_rpc_request(852)
  create_rpc_request: opnum: 0x7 data_len: 0x78
[2005/07/19 13:16:22, 10] rpc_client/cli_pipe.c:create_rpc_request(868)
  create_rpc_request: data_len: 78 auth_len: 20 alloc_hint: 40
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 smb_io_rpc_hdr hdr    
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0000 major     : 05
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0001 minor     : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0002 pkt_type  : 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0003 flags     : 03
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0004 pack_type0: 10
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0005 pack_type1: 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0006 pack_type2: 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0007 pack_type3: 00
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0008 frag_len  : 0078
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      000a auth_len  : 0020
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      000c call_id   : 000008e6
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000010 smb_io_rpc_hdr_req hdr_req
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      0010 alloc_hint: 00000040
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0014 context_id: 0000
[2005/07/19 13:16:22, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0016 opnum     : 0007
[2005/07/19 13:16:22, 5] rpc_client/cli_pipe.c:rpc_api_pipe(421)
  rpc_api_pipe: fnum:8004
[2005/07/19 13:16:22, 5] lib/util.c:show_msg(464)
[2005/07/19 13:16:22, 5] lib/util.c:show_msg(467)
  size=202
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=8
  smb_flg2=55297
  smb_tid=16385
  smb_pid=1905
  smb_uid=28674
  smb_mid=2126
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=  120 (0x78)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 4280 (0x10B8)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=   82 (0x52)
  smb_vwv[11]=  120 (0x78)
  smb_vwv[12]=   82 (0x52)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]=32772 (0x8004)
  smb_bcc=135
[2005/07/19 13:16:22, 10] lib/util.c:dump_data(1995)
  [000] 00 5C 00 50 00 49 00 50  00 45 00 5C 00 00 00 05  .\.P.I.P .E.\....
  [010] 00 00 03 10 00 00 00 78  00 20 00 E6 08 00 00 40  .......x . .....@
  [020] 00 00 00 00 00 07 00 00  00 00 00 7C 64 36 6E 54  ........ ...|d6nT
  [030] 9B 95 46 A9 26 95 05 FA  64 7E 5B 00 00 00 02 04  ..F.&... d~[.....
  [040] 00 00 00 01 04 00 00 00  00 00 05 15 00 00 00 09  ........ ........
  [050] 3A 2A 24 78 0C 92 25 82  8B A6 28 00 00 00 00 44  :*$x..%. ..(....D
  [060] 05 04 00 01 00 00 00 77  00 FF FF FF FF 00 00 61  .......w .......a
  [070] 0D CB CA 30 25 2A 00 14  05 4B 43 D0 D7 96 F3 62  ...0%*.. .KC....b
  [080] 2D 86 E4 BB 48 E5 AD                              -...H.. 
[2005/07/19 13:16:22, 6] lib/util_sock.c:write_socket(449)
  write_socket(17,206)
[2005/07/19 13:16:22, 6] lib/util_sock.c:write_socket(452)
  write_socket(17,206) wrote 206
[2005/07/19 13:16:23, 10] lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 152
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(464)
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(467)
  size=152
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=55297
  smb_tid=16385
  smb_pid=1905
  smb_uid=28674
  smb_mid=2126
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   96 (0x60)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   96 (0x60)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=97
[2005/07/19 13:16:23, 10] lib/util.c:dump_data(1995)
  [000] 00 05 00 02 03 10 00 00  00 60 00 20 00 E6 08 00  ........ .`. ....
  [010] 00 18 00 00 00 00 00 00  00 00 00 00 00 34 08 18  ........ .....4..
  [020] 87 1E F9 62 41 97 6A C8  29 D7 79 9D 14 00 00 00  ...bA.j. ).y.....
  [030] 00 41 00 64 00 6D 00 69  00 44 05 08 00 01 00 00  .A.d.m.i .D......
  [040] 00 77 00 FF FF FF FF 00  00 EC A5 C6 94 CA 7E D9  .w...... ......~.
  [050] 00 15 A1 A5 FC 16 EA 7F  43 00 00 00 00 00 00 00  ........ C.......
  [060] 00                                                . 
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(464)
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(467)
  size=152
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=55297
  smb_tid=16385
  smb_pid=1905
  smb_uid=28674
  smb_mid=2126
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   96 (0x60)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   96 (0x60)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=97
[2005/07/19 13:16:23, 10] lib/util.c:dump_data(1995)
  [000] 00 05 00 02 03 10 00 00  00 60 00 20 00 E6 08 00  ........ .`. ....
  [010] 00 18 00 00 00 00 00 00  00 00 00 00 00 34 08 18  ........ .....4..
  [020] 87 1E F9 62 41 97 6A C8  29 D7 79 9D 14 00 00 00  ...bA.j. ).y.....
  [030] 00 41 00 64 00 6D 00 69  00 44 05 08 00 01 00 00  .A.d.m.i .D......
  [040] 00 77 00 FF FF FF FF 00  00 EC A5 C6 94 CA 7E D9  .w...... ......~.
  [050] 00 15 A1 A5 FC 16 EA 7F  43 00 00 00 00 00 00 00  ........ C.......
  [060] 00                                                . 
[2005/07/19 13:16:23, 5] rpc_client/cli_pipe.c:rpc_check_hdr(134)
  rpc_check_hdr: rdata->data_size = 96
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 smb_io_rpc_hdr rpc_hdr   
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0000 major     : 05
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0001 minor     : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0002 pkt_type  : 02
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0003 flags     : 03
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0004 pack_type0: 10
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0005 pack_type1: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0006 pack_type2: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0007 pack_type3: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0008 frag_len  : 0060
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      000a auth_len  : 0020
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      000c call_id   : 000008e6
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000010 smb_io_rpc_hdr_resp rpc_hdr_resp
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      0010 alloc_hint: 00000018
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0014 context_id: 0000
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0016 cancel_ct : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0017 reserved  : 00
[2005/07/19 13:16:23, 5] rpc_client/cli_pipe.c:rpc_api_pipe(493)
  rpc_api_pipe: len left: 0 smbtrans read: 96
[2005/07/19 13:16:23, 5] rpc_client/cli_pipe.c:rpc_auth_pipe(207)
  rpc_auth_pipe: pkt_type: 2 len: 96 auth_len: 32 NTLMSSP No schannel Yes sign
Yes seal No 
[2005/07/19 13:16:23, 10] rpc_client/cli_pipe.c:rpc_auth_pipe(219)
  rpc_auth_pipe: packet:
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 smb_io_rpc_hdr_auth auth_hdr
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0000 auth_type    : 44
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0001 auth_level   : 05
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0002 padding      : 08
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0003 reserved     : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      0004 auth_context : 00000001
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000008 smb_io_rpc_auth_netsec_chk schannel_auth_sign
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0008 sig  : 77 00 ff ff ff ff 00 00 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0010 seq_num: ec a5 c6 94 ca 7e d9 00 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0018 packet_digest: 15 a1 a5 fc 16 ea 7f 43 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0020 confounder: 00 00 00 00 00 00 00 00 
[2005/07/19 13:16:23, 10] rpc_parse/parse_prs.c:netsec_decode(1558)
  SCHANNEL: netsec_encode seq_num=4233 data_len=32
[2005/07/19 13:16:23, 10] rpc_parse/parse_prs.c:netsec_decode(1578)
  SCHANNEL: netsec_decode seq_num=4233 data_len=32
[2005/07/19 13:16:23, 6] rpc_client/cli_pipe.c:rpc_api_pipe(536)
  rpc_api_pipe: fragment first and last both set
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000018 samr_io_r_open_domain 
[2005/07/19 13:16:23, 6] rpc_parse/parse_prs.c:prs_debug(82)
      000018 smb_io_pol_hnd domain_pol
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0018 data1: 00000000
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          001c data2: 87180834
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          0020 data3: f91e
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          0022 data4: 4162
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
          0024 data5: 97 6a c8 29 d7 79 9d 14 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_ntstatus(671)
      002c status: NT_STATUS_OK
[2005/07/19 13:16:23, 10] rpc_client/cli_samr.c:cli_samr_query_dom_info(1380)
  cli_samr_query_dom_info
[2005/07/19 13:16:23, 5] rpc_parse/parse_samr.c:init_samr_q_query_dom_info(468)
  samr_init_samr_q_query_dom_info
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 samr_io_q_query_dom_info 
[2005/07/19 13:16:23, 6] rpc_parse/parse_prs.c:prs_debug(82)
      000000 smb_io_pol_hnd domain_pol
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0000 data1: 00000000
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
          0004 data2: 87180834
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          0008 data3: f91e
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
          000a data4: 4162
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
          000c data5: 97 6a c8 29 d7 79 9d 14 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0014 switch_value: 0008
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000018 smb_io_rpc_hdr_auth hdr_auth
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0018 auth_type    : 44
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0019 auth_level   : 05
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      001a padding      : 02
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      001b reserved     : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      001c auth_context : 00000001
[2005/07/19 13:16:23, 10] rpc_client/cli_pipe.c:rpc_api_pipe_req(1034)
  SCHANNEL seq_num=4234
[2005/07/19 13:16:23, 10] rpc_parse/parse_prs.c:netsec_encode(1481)
  SCHANNEL: netsec_encode seq_num=4234 data_len=24
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000020 smb_io_rpc_auth_netsec_chk 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0020 sig  : 77 00 ff ff ff ff 00 00 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0028 seq_num: bc 8a 70 22 52 bf b6 c1 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0030 packet_digest: aa 23 46 b2 43 fd 3d c9 
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8s(729)
      0038 confounder: 59 6c 71 75 dd c2 1c 69 
[2005/07/19 13:16:23, 5] rpc_client/cli_pipe.c:create_rpc_request(852)
  create_rpc_request: opnum: 0x8 data_len: 0x58
[2005/07/19 13:16:23, 10] rpc_client/cli_pipe.c:create_rpc_request(868)
  create_rpc_request: data_len: 58 auth_len: 20 alloc_hint: 20
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000000 smb_io_rpc_hdr hdr    
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0000 major     : 05
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0001 minor     : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0002 pkt_type  : 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0003 flags     : 03
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0004 pack_type0: 10
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0005 pack_type1: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0006 pack_type2: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint8(584)
      0007 pack_type3: 00
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0008 frag_len  : 0058
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      000a auth_len  : 0020
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      000c call_id   : 000008e7
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_debug(82)
  000010 smb_io_rpc_hdr_req hdr_req
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint32(642)
      0010 alloc_hint: 00000020
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0014 context_id: 0000
[2005/07/19 13:16:23, 5] rpc_parse/parse_prs.c:prs_uint16(613)
      0016 opnum     : 0008
[2005/07/19 13:16:23, 5] rpc_client/cli_pipe.c:rpc_api_pipe(421)
  rpc_api_pipe: fnum:8004
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(464)
[2005/07/19 13:16:23, 5] lib/util.c:show_msg(467)
  size=170
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=8
  smb_flg2=55297
  smb_tid=16385
  smb_pid=1905
  smb_uid=28674
  smb_mid=2127
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   88 (0x58)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 4280 (0x10B8)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=   82 (0x52)
  smb_vwv[11]=   88 (0x58)
  smb_vwv[12]=   82 (0x52)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]=32772 (0x8004)
  smb_bcc=103
[2005/07/19 13:16:23, 10] lib/util.c:dump_data(1995)
  [000] 00 5C 00 50 00 49 00 50  00 45 00 5C 00 00 00 05  .\.P.I.P .E.\....
  [010] 00 00 03 10 00 00 00 58  00 20 00 E7 08 00 00 20  .......X . ..... 
  [020] 00 00 00 00 00 08 00 00  00 00 00 34 08 18 87 1E  ........ ...4....
  [030] F9 62 41 97 6A C8 29 D7  79 9D 14 08 00 00 00 44  .bA.j.). y......D
  [040] 05 02 00 01 00 00 00 77  00 FF FF FF FF 00 00 BC  .......w ........
  [050] 8A 70 22 52 BF B6 C1 AA  23 46 B2 43 FD 3D C9 59  .p"R.... #F.C.=.Y
  [060] 6C 71 75 DD C2 1C 69                              lqu...i 
[2005/07/19 13:16:23, 6] lib/util_sock.c:write_socket(449)
  write_socket(17,174)
[2005/07/19 13:16:23, 6] lib/util_sock.c:write_socket(452)
  write_socket(17,174) wrote 174
[2005/07/19 13:16:23, 10] lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 152
[2005/07/19 13:16:23, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:23, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:23, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:23, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:23, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:23, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778978, time now = 1121778983, qcachetime = 0)
[2005/07/19 13:16:23, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:23, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:23, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:23, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:24, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:24, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:24, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:24, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:24, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:24, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:24, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:24, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:24, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778984, time now = 1121778984, qcachetime = 0)
[2005/07/19 13:16:24, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:24, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:24, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:24, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
[2005/07/19 13:16:24, 3] printing/printing.c:print_queue_update_internal(1094)
  print_queue_update_internal: 0 jobs in queue for new_pr2
[2005/07/19 13:16:24, 10] printing/printing.c:print_queue_update_internal(1159)
  print_queue_update_internal: printer new_pr2 INFO/total_jobs = 0
[2005/07/19 13:16:24, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid: not  updating lpq cache for print share new_pr2
[2005/07/19 13:16:28, 10] printing/printing.c:start_background_queue(1362)
  start_background_queue: background LPQ thread got a message
[2005/07/19 13:16:28, 10] lib/messages.c:message_dispatch(433)
  message_dispatch: received_signal = 1
[2005/07/19 13:16:28, 10] lib/messages.c:message_dispatch(441)
  message_dispatch: received msg_type=2103 src_pid=9809
[2005/07/19 13:16:28, 10] lib/messages.c:message_dispatch(446)
  message_dispatch: processing message of type 2103.
[2005/07/19 13:16:28, 5] printing/printing.c:print_queue_update_with_lock(1216)
  print_queue_update_with_lock: printer share = new_pr2
[2005/07/19 13:16:28, 4] printing/printing.c:print_cache_expired(1025)
  print_cache_expired: cache expired for queue new_pr2 (last_qscan_time =
1121778984, time now = 1121778988, qcachetime = 0)
[2005/07/19 13:16:28, 5] printing/printing.c:set_updating_pid(864)
  set_updating_pid:  updating lpq cache for print share new_pr2
[2005/07/19 13:16:28, 5] printing/printing.c:print_queue_update_internal(1075)
  print_queue_update_internal: printer = new_pr2, type = 8, lpq command = [new_pr2]
[2005/07/19 13:16:28, 5] printing/print_cups.c:cups_queue_get(723)
  cups_queue_get(new_pr2, 0xbffff4f4, 0xbffff3e0)
[2005/07/19 13:16:28, 10] printing/print_cups.c:cups_server(51)
  cups server left to default localhost
=========================================
smb.conf
=========================================
[global]
idmap uid = 70000-200000
idmap gid = 70000-200000
winbind enum users = no
winbind enum groups = no
winbind cache time = 10
winbind use default domain = yes
printcap name = cups
load printers = yes
printing = cups
cups options = "raw"
force printername = yes
lpq cache time = 0
log file = /local/local1/errorlog/samba.log
max log size = 50
socket options = TCP_NODELAY SO_RCVBUF=8192 SO_SNDBUF=8192
smb ports = 50139
local master = no
domain master = no
preferred master = no
dns proxy = no
template homedir = /local/local1/
template shell = /admin-shell
printer admin = @adminGroup1
addprinter command = /usr/bin/addprinter
deleteprinter command = /usr/bin/removeprinter
show add printer wizard = yes
workgroup = DOMAIN2000
netbios name = ODI-MGT-CE1
wins server = 10.86.41.210
password server = 10.86.41.210
security = domain


[print$]
path = /state/samba/printers
guest ok = yes
browseable = yes
read only = yes
write list = @adminGroup1
force user = root
force group = root


[printers]
comment = All Printers
path = /local/local1/spool/samba
browseable = no
guest ok = yes
writable = no
printable = yes
=======================================
> 
> Also, are you running winbindd on the samba box?
  Yes, We are running winbind in the same box.

> 
> I don't think this is a bug, but rather a misconfiguration
> where the trusted user named Administrator is getting mapped
> to a unix user by the same name.

Comment 6 Gerald (Jerry) Carter 2005-07-19 06:34:47 UTC
in the future, please attach the logs and files as attachments 
and not inline.  Thanks.
Comment 7 Gerald (Jerry) Carter 2005-09-27 09:28:17 UTC
There no evidence here to support your claim.  Please 
retest against 3.0.20 and provide real level 10 debug logs 
for smbd (not a mixture of smbd and winbindd) and a raw 
ethereal trace.